See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4506/display/redirect?page=changes>
Changes: [jpeach] Added a space to an error string. ------------------------------------------ [...truncated 7.84 MB...] 3: I1128 17:08:45.446763 17563 authenticatee.cpp:299] Authentication success 3: I1128 17:08:45.446852 17564 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(164)@172.17.0.2:55459 3: I1128 17:08:45.446880 17563 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(375)@172.17.0.2:55459 3: I1128 17:08:45.447078 17558 slave.cpp:1161] Successfully authenticated with master [email protected]:55459 3: I1128 17:08:45.447290 17558 slave.cpp:1685] Will retry registration in 8.55818ms if necessary 3: I1128 17:08:45.447618 17565 master.cpp:6036] Received register agent message from slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.447767 17565 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1128 17:08:45.448241 17562 master.cpp:6098] Authorized registration of agent at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.448356 17562 master.cpp:6191] Registering agent at slave(164)@172.17.0.2:55459 (00e9fe82468a) with id 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: I1128 17:08:45.448849 17557 registrar.cpp:495] Applied 1 operations in 64986ns; attempting to update the registry 3: I1128 17:08:45.449550 17552 registrar.cpp:552] Successfully updated the registry in 636928ns 3: I1128 17:08:45.449873 17561 master.cpp:6240] Admitted agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.450618 17561 master.cpp:6276] Registered agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1128 17:08:45.450752 17555 slave.cpp:1207] Registered with master [email protected]:55459; given agent ID 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: I1128 17:08:45.450850 17563 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1128 17:08:45.450978 17564 hierarchical.cpp:600] Added agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 (00e9fe82468a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1128 17:08:45.451088 17555 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/meta/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/slave.info' 3: I1128 17:08:45.451534 17555 slave.cpp:1298] Forwarding total oversubscribed resources {} 3: I1128 17:08:45.451717 17555 master.cpp:7091] Received update of agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) with total oversubscribed resources {} 3: I1128 17:08:45.451869 17555 master.cpp:7109] Ignoring update on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) as it reports no changes 3: I1128 17:08:45.452404 17564 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.268717ms 3: I1128 17:08:45.452982 17553 master.cpp:8142] Sending 1 offers to framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 3: I1128 17:08:45.453570 17553 sched.cpp:921] Scheduler::resourceOffers took 119809ns 3: I1128 17:08:45.456058 17552 master.cpp:10063] Removing offer 62ed2669-6ad3-4038-8bdc-f503d2097aca-O0 3: I1128 17:08:45.456240 17552 master.cpp:4230] Processing ACCEPT call for offers: [ 62ed2669-6ad3-4038-8bdc-f503d2097aca-O0 ] on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 3: I1128 17:08:45.456390 17552 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 69be684d-e9e7-454e-94cb-de30f563d96c 3: W1128 17:08:45.458717 17551 validation.cpp:1391] Executor 'default' for task '69be684d-e9e7-454e-94cb-de30f563d96c' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. 3: W1128 17:08:45.458756 17551 validation.cpp:1403] Executor 'default' for task '69be684d-e9e7-454e-94cb-de30f563d96c' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. 3: I1128 17:08:45.459436 17551 master.cpp:10814] Adding task 69be684d-e9e7-454e-94cb-de30f563d96c with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.459902 17551 master.cpp:4978] Launching task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.460845 17558 slave.cpp:1806] Got assigned task '69be684d-e9e7-454e-94cb-de30f563d96c' for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.461787 17558 slave.cpp:2074] Authorizing task '69be684d-e9e7-454e-94cb-de30f563d96c' for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.461875 17558 slave.cpp:7348] Authorizing framework principal 'test-principal' to launch task 69be684d-e9e7-454e-94cb-de30f563d96c 3: I1128 17:08:45.462957 17562 slave.cpp:2242] Launching task '69be684d-e9e7-454e-94cb-de30f563d96c' for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.463695 17562 paths.cpp:621] Trying to chown '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' to user 'mesos' 3: I1128 17:08:45.464006 17562 slave.cpp:7822] Launching executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 with resources [] in work directory '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' 3: I1128 17:08:45.465036 17562 slave.cpp:2470] Queued task '69be684d-e9e7-454e-94cb-de30f563d96c' for executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.465140 17562 slave.cpp:958] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' to virtual path '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/latest' 3: I1128 17:08:45.465195 17562 slave.cpp:958] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' to virtual path '/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/latest' 3: I1128 17:08:45.465243 17562 slave.cpp:958] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' to virtual path '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' 3: I1128 17:08:45.465581 17562 slave.cpp:2933] Launching container c72affc5-63c1-43e7-9faa-8c103ca803c4 for executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: W1128 17:08:45.468673 17557 process.cpp:2756] Attempted to spawn already running process [email protected]:55459 3: I1128 17:08:45.468901 17557 exec.cpp:162] Version: 1.5.0 3: I1128 17:08:45.469278 17559 exec.cpp:212] Executor started at: executor(49)@172.17.0.2:55459 with pid 17550 3: I1128 17:08:45.469476 17560 slave.cpp:4110] Got registration for executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 from executor(49)@172.17.0.2:55459 3: I1128 17:08:45.469854 17554 exec.cpp:237] Executor registered on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: I1128 17:08:45.469903 17554 exec.cpp:249] Executor::registered took 27566ns 3: I1128 17:08:45.470544 17561 slave.cpp:2672] Sending queued task '69be684d-e9e7-454e-94cb-de30f563d96c' to executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 at executor(49)@172.17.0.2:55459 3: I1128 17:08:45.471001 17563 exec.cpp:331] Executor asked to run task '69be684d-e9e7-454e-94cb-de30f563d96c' 3: I1128 17:08:45.471091 17563 exec.cpp:340] Executor::launchTask took 71437ns 3: I1128 17:08:45.471194 17563 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.471470 17551 slave.cpp:4564] Handling status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 from executor(49)@172.17.0.2:55459 3: I1128 17:08:45.472234 17558 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.472275 17558 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.472687 17558 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 to the agent 3: I1128 17:08:45.472980 17565 slave.cpp:5047] Forwarding the update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 to [email protected]:55459 3: I1128 17:08:45.473253 17565 slave.cpp:4940] Task status update manager successfully handled status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.473309 17565 slave.cpp:4956] Sending acknowledgement for status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 to executor(49)@172.17.0.2:55459 3: I1128 17:08:45.473438 17553 master.cpp:7274] Status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 from agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.473497 17553 master.cpp:7336] Forwarding status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.473526 17556 exec.cpp:388] Executor received status update acknowledgement e045303c-de07-4906-8927-492319e461a0 for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.473734 17553 master.cpp:9529] Updating the state of task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1128 17:08:45.474048 17562 sched.cpp:1029] Scheduler::statusUpdate took 74585ns 3: I1128 17:08:45.474361 17562 master.cpp:5793] Processing ACKNOWLEDGE call e045303c-de07-4906-8927-492319e461a0 for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: W1128 17:08:45.475105 17557 slave.cpp:3809] Ignoring status update acknowledgement message from [email protected]:1 because it is not the expected master: [email protected]:55459 3: I1128 17:08:45.475980 17560 hierarchical.cpp:1457] Performed allocation for 1 agents in 134258ns 3: W1128 17:08:45.476286 17555 task_status_update_manager.cpp:484] Resending task status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.476321 17555 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 to the agent 3: I1128 17:08:45.476486 17564 slave.cpp:5047] Forwarding the update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 to [email protected]:55459 3: I1128 17:08:45.476811 17558 master.cpp:7274] Status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 from agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.476864 17558 master.cpp:7336] Forwarding status update TASK_RUNNING (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.477046 17558 master.cpp:9529] Updating the state of task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1128 17:08:45.477347 17565 sched.cpp:1029] Scheduler::statusUpdate took 67661ns 3: I1128 17:08:45.477655 17556 master.cpp:5793] Processing ACKNOWLEDGE call e045303c-de07-4906-8927-492319e461a0 for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: I1128 17:08:45.478065 17566 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.478370 17566 slave.cpp:3848] Task status update manager successfully handled status update acknowledgement (UUID: e045303c-de07-4906-8927-492319e461a0) for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.478504 17550 sched.cpp:2009] Asked to stop the driver 3: I1128 17:08:45.478680 17559 sched.cpp:1191] Stopping framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.478981 17554 master.cpp:8819] Processing TEARDOWN call for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 3: I1128 17:08:45.479015 17554 master.cpp:8831] Removing framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 3: I1128 17:08:45.479032 17554 master.cpp:3333] Deactivating framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (default) at [email protected]:55459 3: I1128 17:08:45.479156 17557 hierarchical.cpp:419] Deactivated framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.479230 17554 master.cpp:9529] Updating the state of task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) 3: I1128 17:08:45.479239 17560 slave.cpp:3270] Asked to shut down framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 by [email protected]:55459 3: I1128 17:08:45.479300 17560 slave.cpp:3295] Shutting down framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.479348 17560 slave.cpp:5923] Shutting down executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 at executor(49)@172.17.0.2:55459 3: I1128 17:08:45.479449 17558 exec.cpp:435] Executor asked to shutdown 3: I1128 17:08:45.479501 17558 exec.cpp:450] Executor::shutdown took 33511ns 3: I1128 17:08:45.479809 17553 slave.cpp:5179] Got exited event for executor(49)@172.17.0.2:55459 3: I1128 17:08:45.480268 17554 master.cpp:9629] Removing task 69be684d-e9e7-454e-94cb-de30f563d96c with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.480612 17554 master.cpp:9658] Removing executor 'default' with resources [] of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.480849 17565 hierarchical.cpp:1132] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 from framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.481392 17557 slave.cpp:5588] Executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 exited with status 0 3: I1128 17:08:45.481428 17561 hierarchical.cpp:358] Removed framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.481499 17557 slave.cpp:5692] Cleaning up executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 at executor(49)@172.17.0.2:55459 3: I1128 17:08:45.481858 17552 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default/runs/c72affc5-63c1-43e7-9faa-8c103ca803c4' for gc 6.99999442446815days in the future 3: I1128 17:08:45.482112 17557 slave.cpp:5799] Cleaning up framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: W1128 17:08:45.482113 17566 master.cpp:7480] Ignoring unknown exited executor 'default' of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 on agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.482127 17563 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000/executors/default' for gc 6.99999442117037days in the future 3: I1128 17:08:45.482230 17554 task_status_update_manager.cpp:289] Closing task status update streams for framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.482275 17554 task_status_update_manager.cpp:538] Cleaning up status update stream for task 69be684d-e9e7-454e-94cb-de30f563d96c of framework 62ed2669-6ad3-4038-8bdc-f503d2097aca-0000 3: I1128 17:08:45.482360 17557 slave.cpp:883] Agent terminating 3: I1128 17:08:45.482353 17551 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_6wxk4k/slaves/62ed2669-6ad3-4038-8bdc-f503d2097aca-S0/frameworks/62ed2669-6ad3-4038-8bdc-f503d2097aca-0000' for gc 6.99999441835556days in the future 3: I1128 17:08:45.482626 17554 master.cpp:1311] Agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) disconnected 3: I1128 17:08:45.482656 17554 master.cpp:3370] Disconnecting agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.482725 17554 master.cpp:3389] Deactivating agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 at slave(164)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.482885 17562 hierarchical.cpp:697] Agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 deactivated 3: I1128 17:08:45.486807 17550 master.cpp:1153] Master terminating 3: I1128 17:08:45.487552 17553 hierarchical.cpp:633] Removed agent 62ed2669-6ad3-4038-8bdc-f503d2097aca-S0 3: [ OK ] SlaveTest.IgnoreNonLeaderStatusUpdateAcknowledgement (76 ms) 3: [ RUN ] SlaveTest.MetricsInMetricsEndpoint 3: I1128 17:08:45.493392 17550 cluster.cpp:162] Creating default 'local' authorizer 3: I1128 17:08:45.496764 17560 master.cpp:448] Master 87b29034-4582-4c59-94ef-fb9b0992acba (00e9fe82468a) started on 172.17.0.2:55459 3: I1128 17:08:45.496789 17560 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Lipszs/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/Lipszs/master" --zk_session_timeout="10secs" 3: I1128 17:08:45.497102 17560 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1128 17:08:45.497112 17560 master.cpp:505] Master only allowing authenticated agents to register 3: I1128 17:08:45.497118 17560 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1128 17:08:45.497125 17560 credentials.hpp:37] Loading credentials for authentication from '/tmp/Lipszs/credentials' 3: I1128 17:08:45.497439 17560 master.cpp:555] Using default 'crammd5' authenticator 3: I1128 17:08:45.497598 17560 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1128 17:08:45.497769 17560 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1128 17:08:45.497915 17560 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1128 17:08:45.498059 17560 master.cpp:634] Authorization enabled 3: I1128 17:08:45.498240 17561 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1128 17:08:45.498291 17559 whitelist_watcher.cpp:77] No whitelist given 3: I1128 17:08:45.501766 17555 master.cpp:2215] Elected as the leading master! 3: I1128 17:08:45.501798 17555 master.cpp:1695] Recovering from registrar 3: I1128 17:08:45.502025 17551 registrar.cpp:347] Recovering registrar 3: I1128 17:08:45.502627 17551 registrar.cpp:391] Successfully fetched the registry (0B) in 556800ns 3: I1128 17:08:45.502744 17551 registrar.cpp:495] Applied 1 operations in 30685ns; attempting to update the registry 3: I1128 17:08:45.503321 17551 registrar.cpp:552] Successfully updated the registry in 516096ns 3: I1128 17:08:45.503464 17551 registrar.cpp:424] Successfully recovered registrar 3: I1128 17:08:45.503865 17552 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1128 17:08:45.503958 17562 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: W1128 17:08:45.509083 17550 process.cpp:2756] Attempted to spawn already running process [email protected]:55459 3: I1128 17:08:45.510167 17550 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1128 17:08:45.510788 17550 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1128 17:08:45.510917 17550 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1128 17:08:45.510953 17550 provisioner.cpp:259] Using default backend 'copy' 3: I1128 17:08:45.513161 17550 cluster.cpp:448] Creating default 'local' authorizer 3: I1128 17:08:45.515704 17561 slave.cpp:262] Mesos agent started on (165)@172.17.0.2:55459 3: I1128 17:08:45.515732 17561 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/SlaveTest_MetricsInMetricsEndpoint_bm6wAu" --zk_session_timeout="10secs" 3: I1128 17:08:45.516170 17561 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/credential' 3: I1128 17:08:45.516319 17561 slave.cpp:295] Agent using credential for: test-principal 3: I1128 17:08:45.516338 17561 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_MetricsInMetricsEndpoint_9zMCBw/http_credentials' 3: I1128 17:08:45.516628 17561 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1128 17:08:45.516775 17561 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1128 17:08:45.517096 17561 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1128 17:08:45.517244 17561 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1128 17:08:45.517530 17561 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1128 17:08:45.517676 17561 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1128 17:08:45.518031 17551 process.cpp:3503] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot' 3: I1128 17:08:45.519129 17566 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot' 3: I1128 17:08:45.519613 17561 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1128 17:08:45.519915 17561 slave.cpp:601] Agent attributes: [ ] 3: I1128 17:08:45.519928 17561 slave.cpp:610] Agent hostname: 00e9fe82468a 3: I1128 17:08:45.520102 17559 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1128 17:08:45.522002 17554 state.cpp:64] Recovering state from '/tmp/SlaveTest_MetricsInMetricsEndpoint_bm6wAu/meta' 3: I1128 17:08:45.523372 17557 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1128 17:08:45.523555 17564 containerizer.cpp:668] Recovering containerizer 3: I1128 17:08:45.525701 17552 provisioner.cpp:455] Provisioner recovery complete 3: I1128 17:08:45.526151 17564 slave.cpp:6493] Finished recovery 3: I1128 17:08:45.526944 17553 slave.cpp:1007] New master detected at [email protected]:55459 3: I1128 17:08:45.526952 17566 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1128 17:08:45.527046 17553 slave.cpp:1042] Detecting new master 3: I1128 17:08:45.535379 17560 slave.cpp:1069] Authenticating with master [email protected]:55459 3: I1128 17:08:45.535472 17560 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1128 17:08:45.535749 17562 authenticatee.cpp:121] Creating new client SASL connection 3: I1128 17:08:45.536097 17562 master.cpp:8312] Authenticating slave(165)@172.17.0.2:55459 3: I1128 17:08:45.536217 17552 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(376)@172.17.0.2:55459 3: I1128 17:08:45.536573 17565 authenticator.cpp:98] Creating new server SASL connection 3: I1128 17:08:45.536880 17564 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1128 17:08:45.536916 17564 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1128 17:08:45.537019 17564 authenticator.cpp:204] Received SASL authentication start 3: I1128 17:08:45.537065 17564 authenticator.cpp:326] Authentication requires more steps 3: I1128 17:08:45.537147 17564 authenticatee.cpp:259] Received SASL authentication step 3: I1128 17:08:45.537269 17564 authenticator.cpp:232] Received SASL authentication step 3: I1128 17:08:45.537297 17564 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '00e9fe82468a' server FQDN: '00e9fe82468a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1128 17:08:45.537308 17564 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1128 17:08:45.537338 17564 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1128 17:08:45.537353 17564 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '00e9fe82468a' server FQDN: '00e9fe82468a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1128 17:08:45.537360 17564 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1128 17:08:45.537365 17564 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1128 17:08:45.537376 17564 authenticator.cpp:318] Authentication success 3: I1128 17:08:45.537505 17561 authenticatee.cpp:299] Authentication success 3: I1128 17:08:45.537531 17562 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(165)@172.17.0.2:55459 3: I1128 17:08:45.537616 17564 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(376)@172.17.0.2:55459 3: I1128 17:08:45.537979 17554 slave.cpp:1161] Successfully authenticated with master [email protected]:55459 3: I1128 17:08:45.538238 17554 slave.cpp:1685] Will retry registration in 6.16234ms if necessary 3: I1128 17:08:45.538452 17557 master.cpp:6036] Received register agent message from slave(165)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.538591 17557 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1128 17:08:45.539077 17566 master.cpp:6098] Authorized registration of agent at slave(165)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.539194 17566 master.cpp:6191] Registering agent at slave(165)@172.17.0.2:55459 (00e9fe82468a) with id 87b29034-4582-4c59-94ef-fb9b0992acba-S0 3: I1128 17:08:45.539801 17558 registrar.cpp:495] Applied 1 operations in 63748ns; attempting to update the registry 3: I1128 17:08:45.540609 17550 slave.cpp:883] Agent terminating 3: I1128 17:08:45.540644 17558 registrar.cpp:552] Successfully updated the registry in 772096ns 3: I1128 17:08:45.540908 17565 master.cpp:6240] Admitted agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 at slave(165)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.541647 17565 master.cpp:6276] Registered agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 at slave(165)@172.17.0.2:55459 (00e9fe82468a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1128 17:08:45.541780 17565 master.cpp:1311] Agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 at slave(165)@172.17.0.2:55459 (00e9fe82468a) disconnected 3: I1128 17:08:45.541802 17565 master.cpp:3370] Disconnecting agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 at slave(165)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.541848 17565 master.cpp:3389] Deactivating agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 at slave(165)@172.17.0.2:55459 (00e9fe82468a) 3: I1128 17:08:45.541977 17554 hierarchical.cpp:600] Added agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 (00e9fe82468a) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1128 17:08:45.542157 17554 hierarchical.cpp:697] Agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 deactivated 3: I1128 17:08:45.542362 17554 hierarchical.cpp:1457] Performed allocation for 1 agents in 123370ns 3: I1128 17:08:45.547410 17551 master.cpp:1153] Master terminating 3: I1128 17:08:45.548225 17562 hierarchical.cpp:633] Removed agent 87b29034-4582-4c59-94ef-fb9b0992acba-S0 3: [ OK ] SlaveTest.MetricsInMetricsEndpoint (60 ms) 3: [ RUN ] SlaveTest.MetricsSlaveLaunchErrors 3: I1128 17:08:45.554553 17550 cluster.cpp:162] Creating default 'local' authorizer 3: I1128 17:08:45.557387 17566 master.cpp:448] Master a8cd52e0-83f0-411e-90d1-bdb6a7edb132 (00e9fe82468a) started on 172.17.0.2:55459 3: I1128 17:08:45.557411 17566 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Hm5rOA/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/Hm5rOA/master" --zk_session_timeout="10secs" 3: I1128 17:08:45.557667 17566 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1128 17:08:45.557674 17566 master.cpp:505] Master only allowing authenticated agents to register 3: I1128 17:08:45.557679 17566 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1128 17:08:45.557684 17566 credentials.hpp:37] Loading credentials for authentication from '/tmp/Hm5rOA/credentials' 3: I1128 17:08:45.557934 17566 master.cpp:555] Using default 'crammd5' authenticator 3: I1128 17:08:45.558068 17566 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1128 17:08:45.558221 17566 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1128 17:08:45.558398 17566 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1128 17:08:45.558537 17566 master.cpp:634] Authorization enabled 3: I1128 17:08:45.558688 17561 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1128 17:08:45.558759 17553 whitelist_watcher.cpp:77] No whitelist given 3: I1128 17:08:45.562304 17554 master.cpp:2215] Elected as the leading master! 3: I1128 17:08:45.562335 17554 master.cpp:1695] Recovering from registrar 3: I1128 17:08:45.562453 17551 registrar.cpp:347] Recovering registrar 3: I1128 17:08:45.563004 17551 registrar.cpp:391] Successfully fetched the registry (0B) in 514048ns 3: I1128 17:08:45.563103 17551 registrar.cpp:495] Applied 1 operations in 27966ns; attempting to update the registry 3: I1128 17:08:45.563685 17551 registrar.cpp:552] Successfully updated the registry in 525056ns 3: I1128 17:08:45.563818 17551 registrar.cpp:424] Successfully recovered registrar 3: I1128 17:08:45.564232 17553 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1128 17:08:45.564298 17558 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: W1128 17:08:45.569916 17550 process.cpp:2756] Attempted to spawn already running process [email protected]:55459 3: I1128 17:08:45.570268 17550 cluster.cpp:448] Creating default 'local' authorizer 3: I1128 17:08:45.573657 17551 slave.cpp:262] Mesos agent started on (166)@172.17.0.2:55459 3: I1128 17:08:45.573680 17551 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/SlaveTest_MetricsSlaveLaunchErrors_Z9HoZC" --zk_session_timeout="10secs" 3: I1128 17:08:45.574173 17551 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/credential' 3: I1128 17:08:45.574362 17551 slave.cpp:295] Agent using credential for: test-principal 3: W1128 17:08:45.574383 17550 process.cpp:2756] Attempted to spawn already running process [email protected]:55459 3: I1128 17:08:45.574393 17551 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_MetricsSlaveLaunchErrors_VKzmaF/http_credentials' 3: I1128 17:08:45.574793 17551 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1128 17:08:45.574934 17551 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1128 17:08:45.575196 17551 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1128 17:08:45.575314 17551 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1128 17:08:45.575547 17551 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1128 17:08:45.575635 17550 sched.cpp:232] Version: 1.5.0 3: I1128 17:08:45.575664 17551 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1128 17:08:45.576419 17554 sched.cpp:336] New master detected at [email protected]:55459 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
