You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/11/27 19:09:23 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4502
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)/4502/display/redirect?page=changes>
Changes:
[bbannier] Made explicit that a 'switch' covers all branches.
[alexr] Fixed a code snippet in libprocess documentation.
[bbannier] Added env var to set default flags for parallel test runner.
------------------------------------------
[...truncated 18.43 MB...]
3: I1127 19:09:16.845329 18309 slave.cpp:1685] Will retry registration in 6.02879ms if necessary
3: I1127 19:09:16.845628 18315 master.cpp:6036] Received register agent message from slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.845824 18315 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.846374 18312 master.cpp:6098] Authorized registration of agent at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.846541 18312 master.cpp:6191] Registering agent at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with id ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.847199 18303 registrar.cpp:495] Applied 1 operations in 92158ns; attempting to update the registry
3: I1127 19:09:16.848021 18303 registrar.cpp:552] Successfully updated the registry in 745984ns
3: I1127 19:09:16.848268 18304 master.cpp:6240] Admitted agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.849161 18304 master.cpp:6276] Registered agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1127 19:09:16.849200 18297 slave.cpp:1207] Registered with master master@172.17.0.3:44651; given agent ID ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.849356 18306 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.849710 18316 hierarchical.cpp:600] Added agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1127 19:09:16.849792 18297 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/meta/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/slave.info'
3: I1127 19:09:16.850558 18297 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1127 19:09:16.850862 18310 master.cpp:7091] Received update of agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources {}
3: I1127 19:09:16.851150 18310 master.cpp:7109] Ignoring update on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) as it reports no changes
3: I1127 19:09:16.851970 18316 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.968801ms
3: I1127 19:09:16.852824 18308 master.cpp:8142] Sending 1 offers to framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.853744 18296 sched.cpp:921] Scheduler::resourceOffers took 146142ns
3: I1127 19:09:16.856565 18299 master.cpp:10063] Removing offer ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-O0
3: I1127 19:09:16.856787 18299 master.cpp:4230] Processing ACCEPT call for offers: [ ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-O0 ] on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.856938 18299 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 02c29c21-2e45-41a2-9070-5a8b1f66c89f
3: W1127 19:09:16.859787 18305 validation.cpp:1391] Executor 'exit 1' for task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1127 19:09:16.859839 18305 validation.cpp:1403] Executor 'exit 1' for task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1127 19:09:16.860651 18305 master.cpp:10814] Adding task 02c29c21-2e45-41a2-9070-5a8b1f66c89f with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.861160 18305 master.cpp:4978] Launching task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651 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 ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.862258 18298 slave.cpp:1806] Got assigned task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.863255 18298 slave.cpp:2074] Authorizing task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.863340 18298 slave.cpp:7348] Authorizing framework principal 'test-principal' to launch task 02c29c21-2e45-41a2-9070-5a8b1f66c89f
3: I1127 19:09:16.864435 18304 slave.cpp:2242] Launching task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.865288 18304 paths.cpp:621] Trying to chown '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to user 'mesos'
3: I1127 19:09:16.865766 18304 slave.cpp:7822] Launching executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 with resources [] in work directory '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec'
3: I1127 19:09:16.867137 18304 slave.cpp:2470] Queued task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.867280 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/latest'
3: I1127 19:09:16.867358 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/latest'
3: I1127 19:09:16.867424 18304 slave.cpp:958] Successfully attached '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' to virtual path '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec'
3: I1127 19:09:16.867849 18304 slave.cpp:2933] Launching container 72be87b1-cd36-4403-bc7f-4e2e5c7eabec for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: W1127 19:09:16.871692 18297 process.cpp:2756] Attempted to spawn already running process version@172.17.0.3:44651
3: I1127 19:09:16.871947 18297 exec.cpp:162] Version: 1.5.0
3: I1127 19:09:16.872412 18316 exec.cpp:212] Executor started at: executor(167)@172.17.0.3:44651 with pid 18294
3: I1127 19:09:16.872700 18316 slave.cpp:4110] Got registration for executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from executor(167)@172.17.0.3:44651
3: I1127 19:09:16.873464 18296 exec.cpp:237] Executor registered on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.873560 18296 exec.cpp:249] Executor::registered took 49283ns
3: I1127 19:09:16.874094 18300 slave.cpp:2672] Sending queued task '02c29c21-2e45-41a2-9070-5a8b1f66c89f' to executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.874624 18295 exec.cpp:331] Executor asked to run task '02c29c21-2e45-41a2-9070-5a8b1f66c89f'
3: I1127 19:09:16.874729 18295 exec.cpp:340] Executor::launchTask took 81126ns
3: I1127 19:09:16.874842 18295 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.875205 18318 slave.cpp:4564] Handling status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from executor(167)@172.17.0.3:44651
3: I1127 19:09:16.875937 18307 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.875986 18307 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.876535 18307 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to the agent
3: I1127 19:09:16.876765 18312 slave.cpp:5047] Forwarding the update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to master@172.17.0.3:44651
3: I1127 19:09:16.876961 18312 slave.cpp:4940] Task status update manager successfully handled status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877012 18312 slave.cpp:4956] Sending acknowledgement for status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 to executor(167)@172.17.0.3:44651
3: I1127 19:09:16.877252 18302 exec.cpp:388] Executor received status update acknowledgement ae3ba803-991d-4359-9527-77f9653e860c for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877372 18299 master.cpp:7274] Status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 from agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.877465 18299 master.cpp:7336] Forwarding status update TASK_RUNNING (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.877727 18299 master.cpp:9529] Updating the state of task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1127 19:09:16.878056 18311 sched.cpp:1029] Scheduler::statusUpdate took 141415ns
3: I1127 19:09:16.878571 18303 master.cpp:5793] Processing ACKNOWLEDGE call ae3ba803-991d-4359-9527-77f9653e860c for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: I1127 19:09:16.879743 18296 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.880187 18300 slave.cpp:3848] Task status update manager successfully handled status update acknowledgement (UUID: ae3ba803-991d-4359-9527-77f9653e860c) for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.880647 18294 sched.cpp:2009] Asked to stop the driver
3: I1127 19:09:16.880805 18315 sched.cpp:1191] Stopping framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881381 18312 master.cpp:8819] Processing TEARDOWN call for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881428 18312 master.cpp:8831] Removing framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881446 18312 master.cpp:3333] Deactivating framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (default) at scheduler-1e248e5d-62f7-418a-b248-2202a8c777d6@172.17.0.3:44651
3: I1127 19:09:16.881656 18314 hierarchical.cpp:419] Deactivated framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881749 18305 slave.cpp:3270] Asked to shut down framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 by master@172.17.0.3:44651
3: I1127 19:09:16.881778 18312 master.cpp:9529] Updating the state of task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1127 19:09:16.881808 18305 slave.cpp:3295] Shutting down framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.881884 18305 slave.cpp:5923] Shutting down executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.882025 18306 exec.cpp:435] Executor asked to shutdown
3: I1127 19:09:16.882088 18306 exec.cpp:450] Executor::shutdown took 38298ns
3: I1127 19:09:16.882334 18313 slave.cpp:5179] Got exited event for executor(167)@172.17.0.3:44651
3: I1127 19:09:16.882999 18312 master.cpp:9629] Removing task 02c29c21-2e45-41a2-9070-5a8b1f66c89f with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.883504 18312 master.cpp:9658] Removing executor 'exit 1' with resources [] of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.883512 18305 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 ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 from framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.883530 18296 slave.cpp:5588] Executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 exited with status 0
3: I1127 19:09:16.883711 18296 slave.cpp:5692] Cleaning up executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 at executor(167)@172.17.0.3:44651
3: I1127 19:09:16.884052 18311 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1/runs/72be87b1-cd36-4403-bc7f-4e2e5c7eabec' for gc 6.99998976951407days in the future
3: I1127 19:09:16.884281 18311 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000/executors/exit 1' for gc 6.99998976627259days in the future
3: I1127 19:09:16.884290 18296 slave.cpp:5799] Cleaning up framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: W1127 19:09:16.884394 18312 master.cpp:7480] Ignoring unknown exited executor 'exit 1' of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000 on agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.884465 18313 task_status_update_manager.cpp:289] Closing task status update streams for framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884481 18315 hierarchical.cpp:358] Removed framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884577 18313 task_status_update_manager.cpp:538] Cleaning up status update stream for task 02c29c21-2e45-41a2-9070-5a8b1f66c89f of framework ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000
3: I1127 19:09:16.884577 18306 gc.cpp:90] Scheduling '/tmp/OversubscriptionTest_QoSFetchResourceUsage_564cQu/slaves/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0/frameworks/ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-0000' for gc 6.99998976358815days in the future
3: I1127 19:09:16.884582 18296 slave.cpp:883] Agent terminating
3: I1127 19:09:16.884847 18316 master.cpp:1311] Agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae) disconnected
3: I1127 19:09:16.884876 18316 master.cpp:3370] Disconnecting agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.884939 18316 master.cpp:3389] Deactivating agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 at slave(455)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.885061 18297 hierarchical.cpp:697] Agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0 deactivated
3: I1127 19:09:16.890352 18294 master.cpp:1153] Master terminating
3: I1127 19:09:16.891150 18301 hierarchical.cpp:633] Removed agent ca1ab2e8-77ff-4db3-bf12-34a853ccf66f-S0
3: [ OK ] OversubscriptionTest.QoSFetchResourceUsage (89 ms)
3: [ RUN ] OversubscriptionTest.Reregistration
3: I1127 19:09:16.905263 18294 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1127 19:09:16.905994 18294 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1127 19:09:16.906147 18294 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1127 19:09:16.906189 18294 provisioner.cpp:259] Using default backend 'copy'
3: I1127 19:09:16.908648 18294 cluster.cpp:448] Creating default 'local' authorizer
3: I1127 19:09:16.911679 18300 slave.cpp:262] Mesos agent started on (456)@172.17.0.3:44651
3: I1127 19:09:16.911726 18300 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu/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/OversubscriptionTest_Reregistration_CbIYwu/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" --resource_estimator="org_apache_mesos_FixedResourceEstimator" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/OversubscriptionTest_Reregistration_CbIYwu" --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/OversubscriptionTest_Reregistration_er0Gvu" --zk_session_timeout="10secs"
3: I1127 19:09:16.912309 18300 credentials.hpp:86] Loading credential for authentication from '/tmp/OversubscriptionTest_Reregistration_CbIYwu/credential'
3: I1127 19:09:16.912595 18300 slave.cpp:295] Agent using credential for: test-principal
3: I1127 19:09:16.912616 18300 credentials.hpp:37] Loading credentials for authentication from '/tmp/OversubscriptionTest_Reregistration_CbIYwu/http_credentials'
3: I1127 19:09:16.912961 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.913190 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.913573 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.913781 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.914104 18300 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.914273 18300 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.916998 18300 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: I1127 19:09:16.917433 18300 slave.cpp:601] Agent attributes: [ ]
3: I1127 19:09:16.917446 18300 slave.cpp:610] Agent hostname: e6f5bee250ae
3: I1127 19:09:16.917659 18317 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.919863 18299 state.cpp:64] Recovering state from '/tmp/OversubscriptionTest_Reregistration_er0Gvu/meta'
3: I1127 19:09:16.920307 18314 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1127 19:09:16.920686 18312 containerizer.cpp:668] Recovering containerizer
3: I1127 19:09:16.923216 18295 provisioner.cpp:455] Provisioner recovery complete
3: I1127 19:09:16.923758 18298 slave.cpp:6493] Finished recovery
3: W1127 19:09:16.930073 18294 process.cpp:2756] Attempted to spawn already running process files@172.17.0.3:44651
3: I1127 19:09:16.930882 18294 cluster.cpp:162] Creating default 'local' authorizer
3: I1127 19:09:16.935088 18297 master.cpp:448] Master b04da3cf-4cb9-45b6-83a9-2ee1c49957d6 (e6f5bee250ae) started on 172.17.0.3:44651
3: I1127 19:09:16.935113 18297 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/Yubquu/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/Yubquu/master" --zk_session_timeout="10secs"
3: I1127 19:09:16.935500 18297 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1127 19:09:16.935510 18297 master.cpp:505] Master only allowing authenticated agents to register
3: I1127 19:09:16.935515 18297 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1127 19:09:16.935520 18297 credentials.hpp:37] Loading credentials for authentication from '/tmp/Yubquu/credentials'
3: I1127 19:09:16.935874 18297 master.cpp:555] Using default 'crammd5' authenticator
3: I1127 19:09:16.936100 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1127 19:09:16.936303 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1127 19:09:16.936448 18297 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1127 19:09:16.936586 18297 master.cpp:634] Authorization enabled
3: I1127 19:09:16.936817 18304 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1127 19:09:16.936842 18303 whitelist_watcher.cpp:77] No whitelist given
3: I1127 19:09:16.941105 18311 master.cpp:2215] Elected as the leading master!
3: I1127 19:09:16.941143 18311 master.cpp:1695] Recovering from registrar
3: I1127 19:09:16.941512 18307 registrar.cpp:347] Recovering registrar
3: I1127 19:09:16.942610 18307 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1127 19:09:16.942775 18307 registrar.cpp:495] Applied 1 operations in 51553ns; attempting to update the registry
3: I1127 19:09:16.943609 18307 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1127 19:09:16.943784 18307 registrar.cpp:424] Successfully recovered registrar
3: I1127 19:09:16.944278 18302 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1127 19:09:16.944326 18299 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: I1127 19:09:16.945641 18314 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.945644 18300 slave.cpp:1007] New master detected at master@172.17.0.3:44651
3: I1127 19:09:16.945756 18300 slave.cpp:1042] Detecting new master
3: I1127 19:09:16.945889 18300 slave.cpp:1069] Authenticating with master master@172.17.0.3:44651
3: I1127 19:09:16.946010 18300 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1127 19:09:16.946400 18316 authenticatee.cpp:121] Creating new client SASL connection
3: I1127 19:09:16.946858 18303 master.cpp:8312] Authenticating slave(456)@172.17.0.3:44651
3: I1127 19:09:16.947021 18304 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(945)@172.17.0.3:44651
3: I1127 19:09:16.947341 18309 authenticator.cpp:98] Creating new server SASL connection
3: I1127 19:09:16.947675 18310 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1127 19:09:16.947701 18310 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1127 19:09:16.947844 18297 authenticator.cpp:204] Received SASL authentication start
3: I1127 19:09:16.947918 18297 authenticator.cpp:326] Authentication requires more steps
3: I1127 19:09:16.948050 18315 authenticatee.cpp:259] Received SASL authentication step
3: I1127 19:09:16.948189 18315 authenticator.cpp:232] Received SASL authentication step
3: I1127 19:09:16.948218 18315 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1127 19:09:16.948238 18315 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1127 19:09:16.948299 18315 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1127 19:09:16.948323 18315 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1127 19:09:16.948336 18315 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.948345 18315 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.948367 18315 authenticator.cpp:318] Authentication success
3: I1127 19:09:16.948511 18301 authenticatee.cpp:299] Authentication success
3: I1127 19:09:16.948601 18306 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(456)@172.17.0.3:44651
3: I1127 19:09:16.948700 18313 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(945)@172.17.0.3:44651
3: I1127 19:09:16.949076 18318 slave.cpp:1161] Successfully authenticated with master master@172.17.0.3:44651
3: I1127 19:09:16.949499 18318 slave.cpp:1685] Will retry registration in 5.942353ms if necessary
3: I1127 19:09:16.949813 18317 master.cpp:6036] Received register agent message from slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.949990 18317 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.950577 18307 master.cpp:6098] Authorized registration of agent at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.950737 18307 master.cpp:6191] Registering agent at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with id b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: I1127 19:09:16.951378 18299 registrar.cpp:495] Applied 1 operations in 90720ns; attempting to update the registry
3: I1127 19:09:16.952271 18299 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1127 19:09:16.952540 18300 master.cpp:6240] Admitted agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.953578 18300 master.cpp:6276] Registered agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1127 19:09:16.953811 18303 slave.cpp:1207] Registered with master master@172.17.0.3:44651; given agent ID b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: I1127 19:09:16.953853 18304 hierarchical.cpp:600] Added agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 (e6f5bee250ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1127 19:09:16.953965 18310 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.954215 18304 hierarchical.cpp:1457] Performed allocation for 1 agents in 169178ns
3: I1127 19:09:16.954345 18303 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/OversubscriptionTest_Reregistration_er0Gvu/meta/slaves/b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0/slave.info'
3: I1127 19:09:16.955013 18303 slave.cpp:1298] Forwarding total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.955605 18311 master.cpp:7091] Received update of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.957108 18315 hierarchical.cpp:667] Agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 (e6f5bee250ae) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; cpus{REV}:2
3: I1127 19:09:16.957545 18315 hierarchical.cpp:1457] Performed allocation for 1 agents in 188327ns
3: I1127 19:09:16.958344 18296 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1127 19:09:16.958384 18306 slave.cpp:1007] New master detected at master@172.17.0.3:44651
3: I1127 19:09:16.958503 18306 slave.cpp:1042] Detecting new master
3: I1127 19:09:16.958736 18306 slave.cpp:1069] Authenticating with master master@172.17.0.3:44651
3: I1127 19:09:16.958835 18306 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1127 19:09:16.959229 18305 authenticatee.cpp:121] Creating new client SASL connection
3: I1127 19:09:16.959615 18313 master.cpp:8312] Authenticating slave(456)@172.17.0.3:44651
3: I1127 19:09:16.959769 18318 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(946)@172.17.0.3:44651
3: I1127 19:09:16.960114 18317 authenticator.cpp:98] Creating new server SASL connection
3: I1127 19:09:16.960397 18295 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1127 19:09:16.960427 18295 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1127 19:09:16.960604 18307 authenticator.cpp:204] Received SASL authentication start
3: I1127 19:09:16.960690 18307 authenticator.cpp:326] Authentication requires more steps
3: I1127 19:09:16.960858 18302 authenticatee.cpp:259] Received SASL authentication step
3: I1127 19:09:16.961036 18298 authenticator.cpp:232] Received SASL authentication step
3: I1127 19:09:16.961074 18298 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1127 19:09:16.961089 18298 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1127 19:09:16.961138 18298 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1127 19:09:16.961163 18298 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e6f5bee250ae' server FQDN: 'e6f5bee250ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1127 19:09:16.961174 18298 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.961181 18298 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1127 19:09:16.961197 18298 authenticator.cpp:318] Authentication success
3: I1127 19:09:16.961346 18314 authenticatee.cpp:299] Authentication success
3: I1127 19:09:16.961431 18308 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(456)@172.17.0.3:44651
3: I1127 19:09:16.961504 18299 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(946)@172.17.0.3:44651
3: I1127 19:09:16.961832 18316 slave.cpp:1161] Successfully authenticated with master master@172.17.0.3:44651
3: I1127 19:09:16.962177 18316 slave.cpp:1685] Will retry registration in 19.889507ms if necessary
3: I1127 19:09:16.962561 18304 master.cpp:6365] Received re-register agent message from agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.962788 18304 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1127 19:09:16.963524 18303 master.cpp:6436] Authorized re-registration of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.963635 18303 master.cpp:6501] Re-registering agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.963951 18303 master.cpp:6608] Sending updated checkpointed resources {} to agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae)
3: I1127 19:09:16.964162 18315 slave.cpp:1343] Re-registered with master master@172.17.0.3:44651
3: I1127 19:09:16.964298 18301 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1127 19:09:16.964340 18315 slave.cpp:1400] Forwarding total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.964835 18315 slave.cpp:3519] Ignoring new checkpointed resources identical to the current version: {}
3: I1127 19:09:16.964915 18296 master.cpp:7091] Received update of agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) with total oversubscribed resources cpus{REV}:2
3: I1127 19:09:16.965194 18296 master.cpp:7109] Ignoring update on agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0 at slave(456)@172.17.0.3:44651 (e6f5bee250ae) as it reports no changes
3: I1127 19:09:16.965504 18294 master.cpp:1153] Master terminating
3: I1127 19:09:16.966328 18310 slave.cpp:5179] Got exited event for master@172.17.0.3:44651
3: I1127 19:09:16.966353 18318 hierarchical.cpp:633] Removed agent b04da3cf-4cb9-45b6-83a9-2ee1c49957d6-S0
3: W1127 19:09:16.966361 18310 slave.cpp:5184] Master disconnected! Waiting for a new master to be elected
3: I1127 19:09:16.973382 18294 slave.cpp:883] Agent terminating
3: [ OK ] OversubscriptionTest.Reregistration (84 ms)
3: [ RUN ] OversubscriptionTest.ReceiveQoSCorrection
3: I1127 19:09:16.988332 18314 slave.cpp:262] Mesos agent started on @172.17.0.3:44651
3: I1127 19:09:16.988368 18314 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/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/OversubscriptionTest_ReceiveQoSCorrection_VrDlov" --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/OversubscriptionTest_ReceiveQoSCorrection_yPw0av" --zk_session_timeout="10secs"
3: I1127 19:09:16.988849 18314 credentials.hpp:86] Loading credential for authentication from '/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/credential'
3: I1127 19:09:16.989058 18314 slave.cpp:295] Agent using credential for: test-principal
3: I1127 19:09:16.989079 18314 credentials.hpp:37] Loading credentials for authentication from '/tmp/OversubscriptionTest_ReceiveQoSCorrection_VrDlov/http_credentials'
3: I1127 19:09:16.989444 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.989596 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1127 19:09:16.989833 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.989939 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1127 19:09:16.990133 18314 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1127 19:09:16.990236 18314 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511807177-13680
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4503
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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)/4503/display/redirect?page=changes>