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)/4665/display/redirect?page=changes>
Changes: [bmahler] Added a utility to shrink scalar resource while keeping its meta-data. ------------------------------------------ [...truncated 2.63 MB...] 3: I1222 23:17:03.038756 18365 containerizer.cpp:2966] Transitioning the state of container eaa16827-1817-4bea-a8bd-70924f6a9b8f from FETCHING to RUNNING 3: I1222 23:17:03.441107 18372 slave.cpp:4321] Got registration for executor 'd389da78-0191-449b-a50a-c95ef9895dab' of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: I1222 23:17:03.450141 18362 slave.cpp:2785] Sending queued task 'd389da78-0191-449b-a50a-c95ef9895dab' to executor 'd389da78-0191-449b-a50a-c95ef9895dab' of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 at executor(1)@172.17.0.2:41674 3: I1222 23:17:03.481050 18356 slave.cpp:4778] Handling status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: I1222 23:17:03.484776 18355 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.484885 18355 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.486166 18355 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to the agent 3: I1222 23:17:03.486801 18365 slave.cpp:5261] Forwarding the update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to [email protected]:45841 3: I1222 23:17:03.487319 18365 slave.cpp:5154] Task status update manager successfully handled status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.487460 18365 slave.cpp:5170] Sending acknowledgement for status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to executor(1)@172.17.0.2:41674 3: I1222 23:17:03.487723 18361 master.cpp:7850] Status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:03.487849 18361 master.cpp:7906] Forwarding status update TASK_STARTING (Status UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.488807 18361 master.cpp:10153] Updating the state of task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING) 3: I1222 23:17:03.491523 18377 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.493820 18369 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.496268 18371 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:03.530216 18357 slave.cpp:4778] Handling status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: I1222 23:17:03.534209 18375 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.535423 18369 slave.cpp:5154] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.535535 18369 slave.cpp:5170] Sending acknowledgement for status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to executor(1)@172.17.0.2:41674 3: I1222 23:17:03.539275 18359 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:03.539688 18359 master.cpp:5843] Processing ACKNOWLEDGE call fb696473-4ac2-4493-9952-166b75091ad3 for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 3: I1222 23:17:03.540338 18355 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.540738 18355 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to the agent 3: I1222 23:17:03.541091 18365 slave.cpp:5261] Forwarding the update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to [email protected]:45841 3: I1222 23:17:03.541508 18365 slave.cpp:4026] Task status update manager successfully handled status update acknowledgement (UUID: fb696473-4ac2-4493-9952-166b75091ad3) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.541851 18360 master.cpp:7850] Status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:03.541935 18360 master.cpp:7906] Forwarding status update TASK_RUNNING (Status UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.542747 18360 master.cpp:10153] Updating the state of task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1222 23:17:03.546623 18361 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.550213 18376 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.553225 18359 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:03.594566 18377 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:03.595059 18377 master.cpp:5843] Processing ACKNOWLEDGE call dc3dacdf-2b03-420a-bcc3-5a85db809a12 for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 3: I1222 23:17:03.595952 18361 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.596608 18375 slave.cpp:4026] Task status update manager successfully handled status update acknowledgement (UUID: dc3dacdf-2b03-420a-bcc3-5a85db809a12) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.625066 18369 slave.cpp:4778] Handling status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: I1222 23:17:03.628129 18370 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.628338 18370 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to the agent 3: I1222 23:17:03.628587 18365 slave.cpp:5261] Forwarding the update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to [email protected]:45841 3: I1222 23:17:03.628890 18365 slave.cpp:5154] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.628957 18365 slave.cpp:5170] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to executor(1)@172.17.0.2:41674 3: I1222 23:17:03.629191 18363 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:03.629272 18363 master.cpp:7906] Forwarding status update TASK_RUNNING (Status UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.629927 18363 master.cpp:10153] Updating the state of task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1222 23:17:03.630949 18367 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.633498 18373 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:03.635440 18360 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:03.673637 18358 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:03.673949 18358 master.cpp:5843] Processing ACKNOWLEDGE call 770a931d-2bb1-4282-91b3-56e78628ba19 for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 3: I1222 23:17:03.674417 18358 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.674780 18367 slave.cpp:4026] Task status update manager successfully handled status update acknowledgement (UUID: 770a931d-2bb1-4282-91b3-56e78628ba19) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.935670 18374 hierarchical.cpp:2426] Filtered offer with cpus:1.9; mem:992; disk:992; ports:[31000-32000] on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 for role * of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:03.935917 18374 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.36013ms 3: I1222 23:17:04.632539 18365 slave.cpp:4778] Handling status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: I1222 23:17:04.636528 18364 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.636816 18364 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to the agent 3: I1222 23:17:04.637313 18356 slave.cpp:5261] Forwarding the update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to [email protected]:45841 3: I1222 23:17:04.637893 18356 slave.cpp:5154] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.638005 18356 slave.cpp:5170] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 to executor(1)@172.17.0.2:41674 3: I1222 23:17:04.638298 18374 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:04.638514 18374 master.cpp:7906] Forwarding status update TASK_RUNNING (Status UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab in health state healthy of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.639482 18374 master.cpp:10153] Updating the state of task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1222 23:17:04.641011 18371 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.643179 18355 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.643873 18355 scheduler.cpp:247] Sending RECONCILE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.646293 18369 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:04.685209 18374 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:04.686862 18374 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:04.687351 18374 master.cpp:5843] Processing ACKNOWLEDGE call 90727747-bf81-498a-855d-df525aae5d68 for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 3: I1222 23:17:04.688032 18357 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.688576 18368 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:04.688661 18354 slave.cpp:4026] Task status update manager successfully handled status update acknowledgement (UUID: 90727747-bf81-498a-855d-df525aae5d68) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.688781 18368 master.cpp:8329] Performing implicit task state reconciliation for framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) 3: I1222 23:17:04.689041 18368 master.cpp:8379] Sending implicit reconciliation state TASK_RUNNING for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) 3: I1222 23:17:04.691040 18369 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.693403 18370 scheduler.cpp:247] Sending TEARDOWN call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.695138 18365 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:04.733948 18362 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41332 3: I1222 23:17:04.734243 18362 master.cpp:9450] Removing framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) 3: I1222 23:17:04.734382 18362 master.cpp:3328] Deactivating framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) 3: I1222 23:17:04.734634 18373 hierarchical.cpp:405] Deactivated framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.734972 18362 master.cpp:10153] Updating the state of task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) 3: I1222 23:17:04.735030 18372 slave.cpp:3406] Asked to shut down framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 by [email protected]:45841 3: I1222 23:17:04.735122 18372 slave.cpp:3431] Shutting down framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.735229 18372 slave.cpp:6126] Shutting down executor 'd389da78-0191-449b-a50a-c95ef9895dab' of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 at executor(1)@172.17.0.2:41674 3: I1222 23:17:04.736428 18362 master.cpp:10259] Removing task d389da78-0191-449b-a50a-c95ef9895dab with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:04.737267 18374 hierarchical.cpp:1192] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 from framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.737932 18374 hierarchical.cpp:344] Removed framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: E1222 23:17:04.739138 18370 scheduler.cpp:712] End-Of-File received from master. The master closed the event stream 3: I1222 23:17:04.740017 18361 scheduler.cpp:468] Re-detecting master 3: I1222 23:17:04.741080 18361 scheduler.cpp:419] Ignoring disconnection attempt from stale connection 3: I1222 23:17:04.741163 18361 scheduler.cpp:419] Ignoring disconnection attempt from stale connection 3: I1222 23:17:04.741348 18361 scheduler.cpp:494] New master detected at [email protected]:45841 3: I1222 23:17:04.741392 18361 scheduler.cpp:503] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1222 23:17:04.746042 18375 scheduler.cpp:385] Connected with the master at http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:04.840348 18367 slave.cpp:4778] Handling status update TASK_KILLED (Status UUID: 6ef09c0c-b072-4b6a-8a2e-3cb2c4b82a7d) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 from executor(1)@172.17.0.2:41674 3: W1222 23:17:04.840507 18367 slave.cpp:4847] Ignoring status update TASK_KILLED (Status UUID: 6ef09c0c-b072-4b6a-8a2e-3cb2c4b82a7d) for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 for terminating framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:04.939944 18377 hierarchical.cpp:1517] Performed allocation for 1 agents in 276202ns 3: I1222 23:17:05.858114 18359 slave.cpp:5393] Got exited event for executor(1)@172.17.0.2:41674 3: I1222 23:17:05.942014 18374 hierarchical.cpp:1517] Performed allocation for 1 agents in 401552ns 3: I1222 23:17:05.942188 18370 containerizer.cpp:2805] Container eaa16827-1817-4bea-a8bd-70924f6a9b8f has exited 3: I1222 23:17:05.942227 18370 containerizer.cpp:2352] Destroying container eaa16827-1817-4bea-a8bd-70924f6a9b8f in RUNNING state 3: I1222 23:17:05.942246 18370 containerizer.cpp:2966] Transitioning the state of container eaa16827-1817-4bea-a8bd-70924f6a9b8f from RUNNING to DESTROYING 3: I1222 23:17:05.942800 18370 launcher.cpp:156] Asked to destroy container eaa16827-1817-4bea-a8bd-70924f6a9b8f 3: I1222 23:17:05.949455 18354 provisioner.cpp:598] Ignoring destroy request for unknown container eaa16827-1817-4bea-a8bd-70924f6a9b8f 3: I1222 23:17:05.950636 18363 slave.cpp:5791] Executor 'd389da78-0191-449b-a50a-c95ef9895dab' of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 exited with status 0 3: I1222 23:17:05.950709 18363 slave.cpp:5895] Cleaning up executor 'd389da78-0191-449b-a50a-c95ef9895dab' of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 at executor(1)@172.17.0.2:41674 3: I1222 23:17:05.951202 18376 gc.cpp:90] Scheduling '/tmp/DefaultExecutorCheckTest_CommandCheckAndHealthCheckNoShadowing_zU9IBp/slaves/7e4037ef-b704-4ba7-a819-cceaca7c4013-S0/frameworks/7e4037ef-b704-4ba7-a819-cceaca7c4013-0000/executors/d389da78-0191-449b-a50a-c95ef9895dab/runs/eaa16827-1817-4bea-a8bd-70924f6a9b8f' for gc 6.99998899351704days in the future 3: I1222 23:17:05.951588 18363 slave.cpp:6002] Cleaning up framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:05.951586 18376 gc.cpp:90] Scheduling '/tmp/DefaultExecutorCheckTest_CommandCheckAndHealthCheckNoShadowing_zU9IBp/slaves/7e4037ef-b704-4ba7-a819-cceaca7c4013-S0/frameworks/7e4037ef-b704-4ba7-a819-cceaca7c4013-0000/executors/d389da78-0191-449b-a50a-c95ef9895dab' for gc 6.99998898789333days in the future 3: I1222 23:17:05.951748 18364 task_status_update_manager.cpp:289] Closing task status update streams for framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:05.951819 18364 task_status_update_manager.cpp:538] Cleaning up status update stream for task d389da78-0191-449b-a50a-c95ef9895dab of framework 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 3: I1222 23:17:05.951863 18376 gc.cpp:90] Scheduling '/tmp/DefaultExecutorCheckTest_CommandCheckAndHealthCheckNoShadowing_zU9IBp/slaves/7e4037ef-b704-4ba7-a819-cceaca7c4013-S0/frameworks/7e4037ef-b704-4ba7-a819-cceaca7c4013-0000' for gc 6.99998898429333days in the future 3: I1222 23:17:05.954592 18353 slave.cpp:931] Agent terminating 3: I1222 23:17:05.955031 18358 master.cpp:1305] Agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) disconnected 3: I1222 23:17:05.955097 18358 master.cpp:3365] Disconnecting agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:05.955193 18358 master.cpp:3384] Deactivating agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 at slave(25)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:05.955525 18366 hierarchical.cpp:766] Agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 deactivated 3: I1222 23:17:05.965225 18353 master.cpp:1147] Master terminating 3: I1222 23:17:05.966640 18357 hierarchical.cpp:609] Removed agent 7e4037ef-b704-4ba7-a819-cceaca7c4013-S0 3: W1222 23:17:05.967989 18353 master.hpp:2728] Failed to close HTTP pipe for 7e4037ef-b704-4ba7-a819-cceaca7c4013-0000 (default) 3: [ OK ] DefaultExecutorCheckTest.CommandCheckAndHealthCheckNoShadowing (3050 ms) 3: [ RUN ] DefaultExecutorCheckTest.MultipleTasksWithChecks 3: I1222 23:17:05.975340 18353 cluster.cpp:172] Creating default 'local' authorizer 3: I1222 23:17:05.979292 18368 master.cpp:456] Master 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee (08af2b027643) started on 172.17.0.2:45841 3: I1222 23:17:05.979326 18368 master.cpp:458] 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/9Pws3X/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/9Pws3X/master" --zk_session_timeout="10secs" 3: I1222 23:17:05.979913 18368 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1222 23:17:05.979928 18368 master.cpp:513] Master only allowing authenticated agents to register 3: I1222 23:17:05.979938 18368 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1222 23:17:05.979948 18368 credentials.hpp:37] Loading credentials for authentication from '/tmp/9Pws3X/credentials' 3: I1222 23:17:05.980485 18368 master.cpp:563] Using default 'crammd5' authenticator 3: I1222 23:17:05.980722 18368 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1222 23:17:05.980988 18368 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1222 23:17:05.981205 18368 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1222 23:17:05.981410 18368 master.cpp:642] Authorization enabled 3: I1222 23:17:05.981662 18358 whitelist_watcher.cpp:77] No whitelist given 3: I1222 23:17:05.981874 18370 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1222 23:17:05.987030 18357 master.cpp:2210] Elected as the leading master! 3: I1222 23:17:05.987063 18357 master.cpp:1689] Recovering from registrar 3: I1222 23:17:05.987310 18376 registrar.cpp:347] Recovering registrar 3: I1222 23:17:05.988374 18376 registrar.cpp:391] Successfully fetched the registry (0B) in 1.0048ms 3: I1222 23:17:05.988576 18376 registrar.cpp:495] Applied 1 operations in 59688ns; attempting to update the registry 3: I1222 23:17:05.989517 18376 registrar.cpp:552] Successfully updated the registry in 854016ns 3: I1222 23:17:05.989715 18376 registrar.cpp:424] Successfully recovered registrar 3: I1222 23:17:05.990334 18363 master.cpp:1803] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1222 23:17:05.990367 18360 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: W1222 23:17:05.996568 18353 process.cpp:2756] Attempted to spawn already running process [email protected]:45841 3: I1222 23:17:05.997701 18353 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1222 23:17:05.998248 18353 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1222 23:17:05.998381 18353 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1222 23:17:05.998417 18353 provisioner.cpp:299] Using default backend 'copy' 3: I1222 23:17:06.000696 18353 cluster.cpp:460] Creating default 'local' authorizer 3: I1222 23:17:06.004168 18363 slave.cpp:262] Mesos agent started on (26)@172.17.0.2:45841 3: I1222 23:17:06.004196 18363 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --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/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/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/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/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/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/jwt_secret_key" --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" --reconfiguration_policy="equal" --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/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs" --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/DefaultExecutorCheckTest_MultipleTasksWithChecks_pbeniI" --zk_session_timeout="10secs" 3: I1222 23:17:06.004973 18363 credentials.hpp:86] Loading credential for authentication from '/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/credential' 3: I1222 23:17:06.005233 18363 slave.cpp:295] Agent using credential for: test-principal 3: I1222 23:17:06.005260 18363 credentials.hpp:37] Loading credentials for authentication from '/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_tEIjxs/http_credentials' 3: I1222 23:17:06.005712 18363 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1222 23:17:06.005914 18363 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1222 23:17:06.005923 18353 scheduler.cpp:188] Version: 1.5.0 3: I1222 23:17:06.006254 18367 scheduler.cpp:311] Using default 'basic' HTTP authenticatee 3: I1222 23:17:06.006286 18363 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1222 23:17:06.006641 18363 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1222 23:17:06.006816 18371 scheduler.cpp:494] New master detected at [email protected]:45841 3: I1222 23:17:06.006850 18371 scheduler.cpp:503] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1222 23:17:06.009387 18363 slave.cpp:612] 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: I1222 23:17:06.009785 18354 scheduler.cpp:385] Connected with the master at http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.009816 18363 slave.cpp:620] Agent attributes: [ ] 3: I1222 23:17:06.009835 18363 slave.cpp:629] Agent hostname: 08af2b027643 3: I1222 23:17:06.010056 18372 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1222 23:17:06.011025 18358 scheduler.cpp:247] Sending SUBSCRIBE call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.012421 18367 state.cpp:66] Recovering state from '/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_pbeniI/meta' 3: I1222 23:17:06.012703 18362 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:06.012868 18372 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1222 23:17:06.013077 18364 containerizer.cpp:674] Recovering containerizer 3: I1222 23:17:06.014592 18367 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41348 3: I1222 23:17:06.014824 18371 provisioner.cpp:495] Provisioner recovery complete 3: I1222 23:17:06.014833 18367 master.cpp:2610] Received subscription request for HTTP framework 'default' 3: I1222 23:17:06.014955 18367 master.cpp:2275] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1222 23:17:06.015246 18354 slave.cpp:6772] Finished recovery 3: I1222 23:17:06.015589 18355 master.cpp:2745] Subscribing framework 'default' with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1222 23:17:06.015831 18355 master.cpp:9149] Adding framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 (default) with roles { } suppressed 3: I1222 23:17:06.016388 18362 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1222 23:17:06.016396 18369 slave.cpp:1055] New master detected at [email protected]:45841 3: I1222 23:17:06.016515 18369 slave.cpp:1110] Detecting new master 3: I1222 23:17:06.016690 18354 hierarchical.cpp:297] Added framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 3: I1222 23:17:06.016975 18354 hierarchical.cpp:1517] Performed allocation for 0 agents in 114216ns 3: I1222 23:17:06.017364 18372 scheduler.cpp:739] Enqueuing event SUBSCRIBED received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.017856 18372 scheduler.cpp:739] Enqueuing event HEARTBEAT received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.023648 18370 slave.cpp:1137] Authenticating with master [email protected]:45841 3: I1222 23:17:06.023746 18370 slave.cpp:1146] Using default CRAM-MD5 authenticatee 3: I1222 23:17:06.024101 18356 authenticatee.cpp:121] Creating new client SASL connection 3: I1222 23:17:06.024485 18365 master.cpp:8928] Authenticating slave(26)@172.17.0.2:45841 3: I1222 23:17:06.024629 18369 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(48)@172.17.0.2:45841 3: I1222 23:17:06.024906 18366 authenticator.cpp:98] Creating new server SASL connection 3: I1222 23:17:06.025187 18373 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1222 23:17:06.025212 18373 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1222 23:17:06.025344 18354 authenticator.cpp:204] Received SASL authentication start 3: I1222 23:17:06.025423 18354 authenticator.cpp:326] Authentication requires more steps 3: I1222 23:17:06.025566 18377 authenticatee.cpp:259] Received SASL authentication step 3: I1222 23:17:06.025710 18363 authenticator.cpp:232] Received SASL authentication step 3: I1222 23:17:06.025739 18363 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '08af2b027643' server FQDN: '08af2b027643' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1222 23:17:06.025753 18363 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1222 23:17:06.025797 18363 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1222 23:17:06.025823 18363 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '08af2b027643' server FQDN: '08af2b027643' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1222 23:17:06.025831 18363 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1222 23:17:06.025836 18363 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1222 23:17:06.025849 18363 authenticator.cpp:318] Authentication success 3: I1222 23:17:06.026024 18371 authenticatee.cpp:299] Authentication success 3: I1222 23:17:06.026089 18374 master.cpp:8958] Successfully authenticated principal 'test-principal' at slave(26)@172.17.0.2:45841 3: I1222 23:17:06.026120 18363 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(48)@172.17.0.2:45841 3: I1222 23:17:06.026407 18357 slave.cpp:1229] Successfully authenticated with master [email protected]:45841 3: I1222 23:17:06.026676 18357 slave.cpp:1708] Will retry registration in 12.414459ms if necessary 3: I1222 23:17:06.026882 18367 master.cpp:6078] Received register agent message from slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.026981 18367 master.cpp:3867] Authorizing agent with principal 'test-principal' 3: I1222 23:17:06.027485 18362 master.cpp:6148] Authorized registration of agent at slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.027575 18362 master.cpp:6241] Registering agent at slave(26)@172.17.0.2:45841 (08af2b027643) with id 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 3: I1222 23:17:06.028187 18358 registrar.cpp:495] Applied 1 operations in 140122ns; attempting to update the registry 3: I1222 23:17:06.029067 18358 registrar.cpp:552] Successfully updated the registry in 798976ns 3: I1222 23:17:06.029253 18370 master.cpp:6289] Admitted agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.030143 18370 master.cpp:6338] Registered agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1222 23:17:06.030181 18366 slave.cpp:1275] Registered with master [email protected]:45841; given agent ID 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 3: I1222 23:17:06.030280 18370 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1222 23:17:06.030560 18366 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/DefaultExecutorCheckTest_MultipleTasksWithChecks_pbeniI/meta/slaves/38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0/slave.info' 3: I1222 23:17:06.030608 18369 hierarchical.cpp:574] Added agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 (08af2b027643) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1222 23:17:06.031216 18366 slave.cpp:1352] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"0ZGvCJE8QhKRlZNhx6LcFA=="},"slave_id":{"value":"38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0"},"update_oversubscribed_resources":true} 3: I1222 23:17:06.031419 18371 master.cpp:7268] Received update of agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) with total oversubscribed resources {} 3: I1222 23:17:06.031703 18371 master.cpp:7362] Ignoring update on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) as it reports no changes 3: I1222 23:17:06.032470 18369 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.632545ms 3: I1222 23:17:06.032953 18361 master.cpp:8758] Sending 1 offers to framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 (default) 3: I1222 23:17:06.035231 18364 scheduler.cpp:739] Enqueuing event OFFERS received from http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.037683 18360 scheduler.cpp:247] Sending ACCEPT call to http://172.17.0.2:45841/master/api/v1/scheduler 3: I1222 23:17:06.039572 18377 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1222 23:17:06.041206 18374 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:41346 3: I1222 23:17:06.043052 18374 master.cpp:10671] Removing offer 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-O0 3: I1222 23:17:06.043431 18374 master.cpp:4274] Processing ACCEPT call for offers: [ 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-O0 ] on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) for framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 (default) 3: I1222 23:17:06.043694 18374 master.cpp:3594] Authorizing framework principal 'test-principal' to launch task 7180997c-e701-446a-9ed1-bcf346dffe1d 3: I1222 23:17:06.044129 18374 master.cpp:3594] Authorizing framework principal 'test-principal' to launch task 1fe12ccd-9eea-48f3-a453-bcf92223341e 3: I1222 23:17:06.050673 18374 master.cpp:11426] Adding task 7180997c-e701-446a-9ed1-bcf346dffe1d with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.051297 18374 master.cpp:11426] Adding task 1fe12ccd-9eea-48f3-a453-bcf92223341e with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.051559 18374 master.cpp:5228] Launching task group { 1fe12ccd-9eea-48f3-a453-bcf92223341e, 7180997c-e701-446a-9ed1-bcf346dffe1d } of framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 (default) with resources cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64 on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 at slave(26)@172.17.0.2:45841 (08af2b027643) 3: I1222 23:17:06.052619 18355 slave.cpp:1831] Got assigned task group containing tasks [ 7180997c-e701-446a-9ed1-bcf346dffe1d, 1fe12ccd-9eea-48f3-a453-bcf92223341e ] for framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 3: I1222 23:17:06.052907 18376 hierarchical.cpp:1192] Recovered cpus(allocated: *):1.7; mem(allocated: *):928; disk(allocated: *):928; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.3; mem(allocated: *):96; disk(allocated: *):96) on agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 from framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 3: I1222 23:17:06.052981 18376 hierarchical.cpp:1238] Framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 filtered agent 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-S0 for 5secs 3: I1222 23:17:06.053892 18355 slave.cpp:2101] Authorizing task group containing tasks [ 7180997c-e701-446a-9ed1-bcf346dffe1d, 1fe12ccd-9eea-48f3-a453-bcf92223341e ] for framework 38ce0915-acbe-49d6-aa12-6cfe6ce8a4ee-0000 write /dev/stdout: resource temporarily unavailable Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information Build step 'Execute shell' marked build as failure
