Skip to content

Instantly share code, notes, and snippets.

@rodrickbrown

rodrickbrown/chronos logs

Created January 18, 2018 18:21
Show Gist options
  • Save rodrickbrown/98d97a4cd8e2bc0b7670c631bd1d6451 to your computer and use it in GitHub Desktop.
Save rodrickbrown/98d97a4cd8e2bc0b7670c631bd1d6451 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
chronos logs
[2018-01-18 18:18:15,976] INFO --------------------- (org.apache.mesos.chronos.scheduler.Main$:26)
[2018-01-18 18:18:16,058] INFO Initializing chronos. (org.apache.mesos.chronos.scheduler.Main$:27)
[2018-01-18 18:18:16,059] INFO --------------------- (org.apache.mesos.chronos.scheduler.Main$:28)
[2018-01-18 18:18:21,662] INFO Wiring up the application (org.apache.mesos.chronos.scheduler.config.MainModule:39)
[2018-01-18 18:18:23,764] INFO Starting (org.apache.curator.framework.imps.CuratorFrameworkImpl:235)
[2018-01-18 18:18:23,770] INFO Client environment:zookeeper.version=3.4.6-1569965, built on 02/20/2014 09:09 GMT (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,770] INFO Client environment:host.name=acfe7d019e4c (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,771] INFO Client environment:java.version=9-internal (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,771] INFO Client environment:java.vendor=Oracle Corporation (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,771] INFO Client environment:java.home=/usr/lib/jvm/java-9-openjdk-amd64 (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,771] INFO Client environment:java.class.path=/usr/bin/chronos (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,772] INFO Client environment:java.library.path=/usr/local/lib:/usr/lib64:/usr/lib (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,772] INFO Client environment:java.io.tmpdir=/tmp (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,772] INFO Client environment:java.compiler=<NA> (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,773] INFO Client environment:os.name=Linux (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,773] INFO Client environment:os.arch=amd64 (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,774] INFO Client environment:os.version=3.10.0-693.5.2.el7.x86_64 (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,774] INFO Client environment:user.name=root (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,775] INFO Client environment:user.home=/root (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,775] INFO Client environment:user.dir=/ (org.apache.zookeeper.ZooKeeper:100)
[2018-01-18 18:18:23,776] INFO Initiating client connection, connectString=master0.prod.mesos:2181,master1.prod.mesos:2181,master2.prod.mesos:2181 sessionTimeout=60000 watcher=org.apache.curator.ConnectionState@7e307087 (org.apache.zookeeper.ZooKeeper:438)
[2018-01-18 18:18:23,792] INFO Connecting to ZK... (org.apache.mesos.chronos.scheduler.config.ZookeeperModule:40)
[2018-01-18 18:18:23,810] INFO Opening socket connection to server ip-172-24-3-202.us-west-2.compute.internal/172.24.3.202:2181. Will not attempt to authenticate using SASL (unknown error) (org.apache.zookeeper.ClientCnxn:975)
[2018-01-18 18:18:23,862] INFO Socket connection established to ip-172-24-3-202.us-west-2.compute.internal/172.24.3.202:2181, initiating session (org.apache.zookeeper.ClientCnxn:852)
[2018-01-18 18:18:23,869] INFO Session establishment complete on server ip-172-24-3-202.us-west-2.compute.internal/172.24.3.202:2181, sessionid = 0x160f5f7e4c40015, negotiated timeout = 40000 (org.apache.zookeeper.ClientCnxn:1235)
[2018-01-18 18:18:23,873] INFO State change: CONNECTED (org.apache.curator.framework.state.ConnectionStateManager:228)
[2018-01-18 18:18:25,862] INFO Adding HTTP support. (mesosphere.chaos.http.HttpModule:47)
[2018-01-18 18:18:25,863] INFO No HTTPS support configured. (mesosphere.chaos.http.HttpModule:50)
[2018-01-18 18:18:25,966] INFO jetty-8.y.z-SNAPSHOT (org.eclipse.jetty.server.Server:272)
[2018-01-18 18:18:25,970] INFO Trying to become leader. (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:584)
[2018-01-18 18:18:26,866] INFO Registering com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider as a provider class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:113)
[2018-01-18 18:18:26,866] INFO Registering com.codahale.metrics.jersey.InstrumentedResourceMethodDispatchAdapter as a provider class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:113)
[2018-01-18 18:18:26,867] INFO Registering mesosphere.chaos.validation.ConstraintViolationExceptionMapper as a provider class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:113)
[2018-01-18 18:18:26,868] INFO Registering org.apache.mesos.chronos.scheduler.api.LeaderResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,868] INFO Registering org.apache.mesos.chronos.scheduler.api.Iso8601JobResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,868] INFO Registering org.apache.mesos.chronos.scheduler.api.DependentJobResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,868] INFO Registering org.apache.mesos.chronos.scheduler.api.JobManagementResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,869] INFO Registering org.apache.mesos.chronos.scheduler.api.TaskManagementResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,869] INFO Registering org.apache.mesos.chronos.scheduler.api.GraphManagementResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,869] INFO Registering org.apache.mesos.chronos.scheduler.api.StatsResource as a root resource class (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:116)
[2018-01-18 18:18:26,871] INFO Initiating Jersey application, version 'Jersey: 1.18.1 02/19/2014 03:28 AM' (com.sun.jersey.server.impl.application.WebApplicationImpl:815)
[2018-01-18 18:18:27,366] INFO Binding com.codahale.metrics.jersey.InstrumentedResourceMethodDispatchAdapter to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:27,469] INFO Binding mesosphere.chaos.validation.ConstraintViolationExceptionMapper to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:27,470] INFO Binding com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:27,859] INFO HV000001: Hibernate Validator 5.1.2.Final (org.hibernate.validator.internal.util.Version:27)
[2018-01-18 18:18:32,057] INFO Binding org.apache.mesos.chronos.scheduler.api.LeaderResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,067] INFO Binding org.apache.mesos.chronos.scheduler.api.Iso8601JobResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,157] INFO Binding org.apache.mesos.chronos.scheduler.api.DependentJobResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,261] INFO Binding org.apache.mesos.chronos.scheduler.api.JobManagementResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,264] INFO Binding org.apache.mesos.chronos.scheduler.api.TaskManagementResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,266] INFO Binding org.apache.mesos.chronos.scheduler.api.GraphManagementResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,268] INFO Binding org.apache.mesos.chronos.scheduler.api.StatsResource to GuiceManagedComponentProvider with the scope "Singleton" (com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory:168)
[2018-01-18 18:18:32,459] INFO Elected as leader. (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:619)
[2018-01-18 18:18:32,464] INFO Loading tasks (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:625)
[2018-01-18 18:18:32,658] INFO Started SelectChannelConnector@0.0.0.0:31170 (org.eclipse.jetty.server.AbstractConnector:338)
[2018-01-18 18:18:32,660] INFO All services up and running. (org.apache.mesos.chronos.scheduler.Main$:50)
ct:1516298940000:0:test-job:
ct:1516299060000:0:test-job:
ct:1516299420000:0:test-job:
ct:1516299180000:0:test-job:
ct:1516299300000:0:test-job:
[2018-01-18 18:18:33,756] INFO Purging entry 'T_ct:1516298940000:0:test-job:' via: org.apache.mesos.state.ZooKeeperState (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:168)
[2018-01-18 18:18:33,857] INFO Filtering out old task 'ct:1516298940000:0:test-job:' overdue by '-573657' ms and removing from store. (org.apache.mesos.chronos.scheduler.jobs.TaskUtils$:98)
[2018-01-18 18:18:33,858] INFO Purging entry 'T_ct:1516299060000:0:test-job:' via: org.apache.mesos.state.ZooKeeperState (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:168)
[2018-01-18 18:18:33,862] INFO Filtering out old task 'ct:1516299060000:0:test-job:' overdue by '-453658' ms and removing from store. (org.apache.mesos.chronos.scheduler.jobs.TaskUtils$:98)
[2018-01-18 18:18:33,863] INFO Purging entry 'T_ct:1516299420000:0:test-job:' via: org.apache.mesos.state.ZooKeeperState (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:168)
[2018-01-18 18:18:33,869] INFO Filtering out old task 'ct:1516299420000:0:test-job:' overdue by '-93660' ms and removing from store. (org.apache.mesos.chronos.scheduler.jobs.TaskUtils$:98)
[2018-01-18 18:18:33,870] INFO Purging entry 'T_ct:1516299180000:0:test-job:' via: org.apache.mesos.state.ZooKeeperState (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:168)
[2018-01-18 18:18:33,873] INFO Filtering out old task 'ct:1516299180000:0:test-job:' overdue by '-333661' ms and removing from store. (org.apache.mesos.chronos.scheduler.jobs.TaskUtils$:98)
[2018-01-18 18:18:33,956] INFO Purging entry 'T_ct:1516299300000:0:test-job:' via: org.apache.mesos.state.ZooKeeperState (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:168)
[2018-01-18 18:18:33,961] INFO Filtering out old task 'ct:1516299300000:0:test-job:' overdue by '-213662' ms and removing from store. (org.apache.mesos.chronos.scheduler.jobs.TaskUtils$:98)
[2018-01-18 18:18:33,961] INFO Loading jobs (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:627)
[2018-01-18 18:18:34,057] INFO Registering jobs:1 (org.apache.mesos.chronos.scheduler.jobs.JobUtils$:76)
[2018-01-18 18:18:34,265] WARN Adding vertex:test-job (org.apache.mesos.chronos.scheduler.graph.JobGraph:65)
[2018-01-18 18:18:34,266] WARN Current number of vertices:1 (org.apache.mesos.chronos.scheduler.graph.JobGraph:72)
[2018-01-18 18:18:34,362] INFO Adding schedule for time:6:18:34 PM Coordinated Universal Time (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:722)
[2018-01-18 18:18:34,364] INFO Checking schedules with time horizon:PT60S (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:480)
[2018-01-18 18:18:34,364] INFO Calling next for stream: R/2018-01-18T13:19:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:18:34,365] INFO Task ready for scheduling: 2018-01-18T13:19:00.000-05:00 (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:542)
[2018-01-18 18:18:34,367] INFO Scheduling:test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:664)
[2018-01-18 18:18:34,456] INFO Scheduling task 'ct:1516299540000:0:test-job:' with delay: '25633' (org.apache.mesos.chronos.scheduler.jobs.TaskManager:190)
[2018-01-18 18:18:34,569] INFO State T_ct:1516299540000:0:test-job: does not exist yet. Adding to state (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:77)
[2018-01-18 18:18:34,670] INFO State update successful: true (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:92)
[2018-01-18 18:18:34,757] INFO Saving updated job:ScheduleBasedJob(R/2018-01-18T13:21:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer()) (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:695)
[2018-01-18 18:18:34,757] INFO Persisting job 'test-job' with data 'ScheduleBasedJob(R/2018-01-18T13:21:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer())' (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:63)
[2018-01-18 18:18:34,767] INFO Key for state exists already: J_test-job (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:79)
[2018-01-18 18:18:34,772] INFO State update successful: true (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:92)
[2018-01-18 18:18:34,772] INFO Calling next for stream: R/2018-01-18T13:21:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:18:34,859] INFO Starting chronos driver (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:651)
[2018-01-18 18:18:34,864] INFO Running background thread (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:642)
[2018-01-18 18:18:34,864] INFO Starting run loop for JobScheduler. CurrentTime: 2018-01-18T18:18:34.864Z (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:485)
[2018-01-18 18:18:34,956] INFO Size of streams: 1 (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:488)
[2018-01-18 18:18:34,957] INFO Checking schedules with time horizon:PT60S (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:480)
[2018-01-18 18:18:34,957] INFO Calling next for stream: R/2018-01-18T13:21:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:18:37,966] INFO 172.24.10.158 - - [18/Jan/2018:18:18:37 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:38,815] INFO 172.24.15.6 - - [18/Jan/2018:18:18:38 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:39,057] INFO 172.24.10.45 - - [18/Jan/2018:18:18:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:39,062] INFO 172.17.0.1 - - [18/Jan/2018:18:18:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:47,772] INFO 172.24.10.158 - - [18/Jan/2018:18:18:47 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:48,978] INFO 172.24.15.6 - - [18/Jan/2018:18:18:48 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:49,221] INFO 172.24.10.45 - - [18/Jan/2018:18:18:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:49,419] INFO 172.17.0.1 - - [18/Jan/2018:18:18:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:57,792] INFO 172.24.10.158 - - [18/Jan/2018:18:18:57 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:58,888] INFO 172.24.15.6 - - [18/Jan/2018:18:18:58 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:59,133] INFO 172.24.10.45 - - [18/Jan/2018:18:18:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:18:59,531] INFO 172.17.0.1 - - [18/Jan/2018:18:18:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:00,305] INFO Triggering: 'test-job' (org.apache.mesos.chronos.scheduler.jobs.TaskManager:20)
[2018-01-18 18:19:00,305] INFO Removing task mapping (org.apache.mesos.chronos.scheduler.jobs.TaskManager:121)
[2018-01-18 18:19:00,308] INFO JobNotificationObserver does not handle JobQueued(ScheduleBasedJob(R/2018-01-18T13:21:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer()),ct:1516299540000:0:test-job:,0) (org.apache.mesos.chronos.scheduler.jobs.JobsObserver$:27)
[2018-01-18 18:19:00,314] INFO Updating state for job (test-job) to queued (org.apache.mesos.chronos.scheduler.jobs.stats.JobStats:64)
[2018-01-18 18:19:07,812] INFO 172.24.10.158 - - [18/Jan/2018:18:19:07 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:09,129] INFO 172.24.15.6 - - [18/Jan/2018:18:19:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:09,364] INFO 172.17.0.1 - - [18/Jan/2018:18:19:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:09,375] INFO 172.24.10.45 - - [18/Jan/2018:18:19:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:17,832] INFO 172.24.10.158 - - [18/Jan/2018:18:19:17 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:19,049] INFO 172.17.0.1 - - [18/Jan/2018:18:19:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:19,584] INFO 172.24.15.6 - - [18/Jan/2018:18:19:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:19,831] INFO 172.24.10.45 - - [18/Jan/2018:18:19:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:27,852] INFO 172.24.10.158 - - [18/Jan/2018:18:19:27 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:29,498] INFO 172.17.0.1 - - [18/Jan/2018:18:19:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:29,748] INFO 172.24.15.6 - - [18/Jan/2018:18:19:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:29,994] INFO 172.24.10.45 - - [18/Jan/2018:18:19:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:34,963] INFO Size of streams: 1 (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:488)
[2018-01-18 18:19:34,963] INFO Checking schedules with time horizon:PT60S (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:480)
[2018-01-18 18:19:34,963] INFO Calling next for stream: R/2018-01-18T13:21:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:19:37,871] INFO 172.24.10.158 - - [18/Jan/2018:18:19:37 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:39,331] INFO 172.17.0.1 - - [18/Jan/2018:18:19:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:39,790] INFO 172.24.15.6 - - [18/Jan/2018:18:19:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:40,037] INFO 172.24.10.45 - - [18/Jan/2018:18:19:40 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:47,892] INFO 172.24.10.158 - - [18/Jan/2018:18:19:47 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:49,362] INFO 172.17.0.1 - - [18/Jan/2018:18:19:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:50,064] INFO 172.24.15.6 - - [18/Jan/2018:18:19:50 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:50,311] INFO 172.24.10.45 - - [18/Jan/2018:18:19:50 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:57,912] INFO 172.24.10.158 - - [18/Jan/2018:18:19:57 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:59,545] INFO 172.17.0.1 - - [18/Jan/2018:18:19:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:19:59,909] INFO 172.24.15.6 - - [18/Jan/2018:18:19:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:00,156] INFO 172.24.10.45 - - [18/Jan/2018:18:20:00 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:07,932] INFO 172.24.10.158 - - [18/Jan/2018:18:20:07 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:09,205] INFO 172.17.0.1 - - [18/Jan/2018:18:20:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:09,707] INFO 172.24.15.6 - - [18/Jan/2018:18:20:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:09,955] INFO 172.24.10.45 - - [18/Jan/2018:18:20:09 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:17,953] INFO 172.24.10.158 - - [18/Jan/2018:18:20:17 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:19,351] INFO 172.17.0.1 - - [18/Jan/2018:18:20:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:19,596] INFO 172.24.15.6 - - [18/Jan/2018:18:20:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:19,844] INFO 172.24.10.45 - - [18/Jan/2018:18:20:19 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:27,972] INFO 172.24.10.158 - - [18/Jan/2018:18:20:27 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:29,285] INFO 172.24.15.6 - - [18/Jan/2018:18:20:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:29,394] INFO 172.17.0.1 - - [18/Jan/2018:18:20:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:29,533] INFO 172.24.10.45 - - [18/Jan/2018:18:20:29 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:34,965] INFO Size of streams: 1 (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:488)
[2018-01-18 18:20:34,965] INFO Checking schedules with time horizon:PT60S (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:480)
[2018-01-18 18:20:34,965] INFO Calling next for stream: R/2018-01-18T13:21:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:20:34,966] INFO Task ready for scheduling: 2018-01-18T13:21:00.000-05:00 (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:542)
[2018-01-18 18:20:34,967] INFO Scheduling:test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:664)
[2018-01-18 18:20:34,967] INFO Scheduling task 'ct:1516299660000:0:test-job:' with delay: '25033' (org.apache.mesos.chronos.scheduler.jobs.TaskManager:190)
[2018-01-18 18:20:34,969] INFO State T_ct:1516299660000:0:test-job: does not exist yet. Adding to state (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:77)
[2018-01-18 18:20:34,980] INFO State update successful: true (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:92)
[2018-01-18 18:20:34,981] INFO Saving updated job:ScheduleBasedJob(R/2018-01-18T13:23:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer()) (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:695)
[2018-01-18 18:20:34,981] INFO Persisting job 'test-job' with data 'ScheduleBasedJob(R/2018-01-18T13:23:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer())' (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:63)
[2018-01-18 18:20:34,986] INFO Key for state exists already: J_test-job (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:79)
[2018-01-18 18:20:34,989] INFO State update successful: true (org.apache.mesos.chronos.scheduler.state.MesosStatePersistenceStore:92)
[2018-01-18 18:20:34,990] INFO Calling next for stream: R/2018-01-18T13:23:00.000-05:00/PT2M, jobname: test-job (org.apache.mesos.chronos.scheduler.jobs.JobScheduler:509)
[2018-01-18 18:20:37,992] INFO 172.24.10.158 - - [18/Jan/2018:18:20:37 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:39,263] INFO 172.24.15.6 - - [18/Jan/2018:18:20:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:39,512] INFO 172.24.10.45 - - [18/Jan/2018:18:20:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:39,785] INFO 172.17.0.1 - - [18/Jan/2018:18:20:39 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:48,012] INFO 172.24.10.158 - - [18/Jan/2018:18:20:48 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:49,528] INFO 172.24.15.6 - - [18/Jan/2018:18:20:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:49,778] INFO 172.24.10.45 - - [18/Jan/2018:18:20:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:49,847] INFO 172.17.0.1 - - [18/Jan/2018:18:20:49 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:58,032] INFO 172.24.10.158 - - [18/Jan/2018:18:20:58 +0000] "GET / HTTP/1.1" 200 3492 "-" "akka-http/10.0.6" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:59,272] INFO 172.24.15.6 - - [18/Jan/2018:18:20:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:59,522] INFO 172.24.10.45 - - [18/Jan/2018:18:20:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:20:59,762] INFO 172.17.0.1 - - [18/Jan/2018:18:20:59 +0000] "GET / HTTP/1.0" 200 3492 "-" "-" (mesosphere.chaos.http.ChaosRequestLog:15)
[2018-01-18 18:21:00,014] INFO Triggering: 'test-job' (org.apache.mesos.chronos.scheduler.jobs.TaskManager:20)
[2018-01-18 18:21:00,014] INFO Removing task mapping (org.apache.mesos.chronos.scheduler.jobs.TaskManager:121)
[2018-01-18 18:21:00,015] INFO JobNotificationObserver does not handle JobQueued(ScheduleBasedJob(R/2018-01-18T13:23:00.000-05:00/PT2M,test-job,bash -c 'this is a test id' > /tmp/test.$$,PT60S,0,0,,,,3,rodrick@octanelending.com,,Takes snapshot of postgres data and populates redshift,,,false,0.5,256.0,100.0,false,0,ListBuffer(),ListBuffer(),false,octane,null,EST,ListBuffer(EnvironmentVariable(JVMOPTS,-Xmx3g)),true,ListBuffer(),false,false,ListBuffer()),ct:1516299660000:0:test-job:,0) (org.apache.mesos.chronos.scheduler.jobs.JobsObserver$:27)
[2018-01-18 18:21:00,015] INFO Updating state for job (test-job) to queued (org.apache.mesos.chronos.scheduler.jobs.stats.JobStats:64)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment