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/25 05:10:14 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4497
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4497/display/redirect>
------------------------------------------
[...truncated 12.65 MB...]
3: I1125 05:10:13.206679 16015 slave.cpp:1685] Will retry registration in 16.547949ms if necessary
3: I1125 05:10:13.206903 15995 master.cpp:6036] Received register agent message from slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.207054 15995 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1125 05:10:13.207530 15999 master.cpp:6098] Authorized registration of agent at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.207648 15999 master.cpp:6191] Registering agent at slave(268)@172.17.0.7:42876 (5772de05b3f2) with id eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: I1125 05:10:13.208123 16006 registrar.cpp:495] Applied 1 operations in 73173ns; attempting to update the registry
3: I1125 05:10:13.208833 16006 registrar.cpp:552] Successfully updated the registry in 637184ns
3: I1125 05:10:13.209049 16012 master.cpp:6240] Admitted agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.209836 16012 master.cpp:6276] Registered agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2) with cpus:2; disk:367489; ports:[31000-32000]
3: I1125 05:10:13.209919 16009 slave.cpp:1207] Registered with master master@172.17.0.7:42876; given agent ID eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: I1125 05:10:13.210041 15992 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1125 05:10:13.210155 16007 hierarchical.cpp:600] Added agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 (5772de05b3f2) with cpus:2; disk:367489; ports:[31000-32000] (allocated: {})
3: I1125 05:10:13.210395 16009 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/meta/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/slave.info'
3: I1125 05:10:13.210964 16009 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1125 05:10:13.211165 16004 master.cpp:7091] Received update of agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2) with total oversubscribed resources {}
3: I1125 05:10:13.211335 16004 master.cpp:7109] Ignoring update on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2) as it reports no changes
3: I1125 05:10:13.211345 16007 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.016034ms
3: I1125 05:10:13.211825 16004 master.cpp:8142] Sending 1 offers to framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.212952 16000 sched.cpp:921] Scheduler::resourceOffers took 691078ns
3: I1125 05:10:13.214152 15998 master.cpp:10063] Removing offer eb94d6e6-00a3-4c13-81ff-367343f7aa23-O0
3: I1125 05:10:13.214289 15998 master.cpp:4230] Processing ACCEPT call for offers: [ eb94d6e6-00a3-4c13-81ff-367343f7aa23-O0 ] on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2) for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.214390 15998 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 0
3: W1125 05:10:13.216004 16005 validation.cpp:1391] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1125 05:10:13.216042 16005 validation.cpp:1403] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1125 05:10:13.216400 16005 master.cpp:10814] Adding task 0 with resources cpus(allocated: *):2 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.216612 16005 master.cpp:4978] Launching task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"}] on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.217195 16015 slave.cpp:1806] Got assigned task '0' for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.217703 15995 hierarchical.cpp:1132] Recovered ports(allocated: *):[31000-32000]; disk(allocated: *):367489 (total: cpus:2; disk:367489; ports:[31000-32000], allocated: cpus(allocated: *):2) on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 from framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.217774 15995 hierarchical.cpp:1178] Framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 filtered agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 for 5secs
3: I1125 05:10:13.217859 16015 slave.cpp:2074] Authorizing task '0' for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.217931 16015 slave.cpp:7349] Authorizing framework principal 'test-principal' to launch task 0
3: I1125 05:10:13.218591 15993 slave.cpp:2242] Launching task '0' for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.219194 15993 paths.cpp:621] Trying to chown '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7' to user 'mesos'
3: I1125 05:10:13.219489 15993 slave.cpp:7823] Launching executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 with resources [] in work directory '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7'
3: I1125 05:10:13.219985 15993 slave.cpp:2933] Launching container 3b49f98a-95d8-4c24-b01e-e1ea9a804fe7 for executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.220326 15993 slave.cpp:2470] Queued task '0' for executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.220410 15993 slave.cpp:958] Successfully attached '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7' to virtual path '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/latest'
3: I1125 05:10:13.220455 15993 slave.cpp:958] Successfully attached '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7' to virtual path '/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/latest'
3: I1125 05:10:13.220504 15993 slave.cpp:958] Successfully attached '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7' to virtual path '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7'
3: W1125 05:10:13.222713 16001 process.cpp:2756] Attempted to spawn already running process version@172.17.0.7:42876
3: I1125 05:10:13.222916 16001 exec.cpp:162] Version: 1.5.0
3: I1125 05:10:13.223225 15997 exec.cpp:212] Executor started at: executor(96)@172.17.0.7:42876 with pid 15991
3: I1125 05:10:13.223489 15994 slave.cpp:4110] Got registration for executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 from executor(96)@172.17.0.7:42876
3: I1125 05:10:13.223918 16012 exec.cpp:237] Executor registered on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: I1125 05:10:13.223992 16012 exec.cpp:249] Executor::registered took 53121ns
3: I1125 05:10:13.224364 15994 slave.cpp:2672] Sending queued task '0' to executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 at executor(96)@172.17.0.7:42876
3: I1125 05:10:13.224696 16003 exec.cpp:331] Executor asked to run task '0'
3: I1125 05:10:13.224869 16003 exec.cpp:340] Executor::launchTask took 150862ns
3: I1125 05:10:13.224990 16003 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.225345 16011 slave.cpp:4564] Handling status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 from executor(96)@172.17.0.7:42876
3: I1125 05:10:13.225386 15998 exec.cpp:571] Executor sending status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.225711 16011 slave.cpp:4564] Handling status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 from executor(96)@172.17.0.7:42876
3: I1125 05:10:13.226181 16015 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.226223 16015 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.226307 15999 hierarchical.cpp:1457] Performed allocation for 1 agents in 224831ns
3: I1125 05:10:13.226717 16015 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to the agent
3: I1125 05:10:13.226919 16002 slave.cpp:5047] Forwarding the update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to master@172.17.0.7:42876
3: I1125 05:10:13.227010 16015 task_status_update_manager.cpp:328] Received task status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227141 16002 slave.cpp:4940] Task status update manager successfully handled status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227195 16002 slave.cpp:4956] Sending acknowledgement for status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to executor(96)@172.17.0.7:42876
3: I1125 05:10:13.227319 16006 master.cpp:7274] Status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 from agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.227340 16002 slave.cpp:4940] Task status update manager successfully handled status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227375 16006 master.cpp:7336] Forwarding status update TASK_RUNNING (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227392 15993 exec.cpp:388] Executor received status update acknowledgement b119130e-ca71-4b1f-bdcf-aecbc4b6bd59 for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227387 16002 slave.cpp:4956] Sending acknowledgement for status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to executor(96)@172.17.0.7:42876
3: I1125 05:10:13.227535 15993 exec.cpp:388] Executor received status update acknowledgement 210d1a55-2211-4a14-b9db-0c9f0a6be2b6 for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.227576 16006 master.cpp:9529] Updating the state of task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
3: I1125 05:10:13.227723 16010 sched.cpp:1029] Scheduler::statusUpdate took 21786ns
3: I1125 05:10:13.228060 16006 master.cpp:5793] Processing ACKNOWLEDGE call b119130e-ca71-4b1f-bdcf-aecbc4b6bd59 for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: I1125 05:10:13.228082 15997 hierarchical.cpp:1132] Recovered cpus(allocated: *):2 (total: cpus:2; disk:367489; ports:[31000-32000], allocated: {}) on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 from framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.228363 16012 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.228500 16012 task_status_update_manager.cpp:383] Forwarding task status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to the agent
3: I1125 05:10:13.228716 15992 slave.cpp:5047] Forwarding the update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 to master@172.17.0.7:42876
3: I1125 05:10:13.228900 15992 slave.cpp:3848] Task status update manager successfully handled status update acknowledgement (UUID: b119130e-ca71-4b1f-bdcf-aecbc4b6bd59) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.229024 15994 master.cpp:7274] Status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 from agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.229071 15994 master.cpp:7336] Forwarding status update TASK_FINISHED (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.229243 15994 master.cpp:9529] Updating the state of task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
3: I1125 05:10:13.229419 16007 sched.cpp:1029] Scheduler::statusUpdate took 15791ns
3: I1125 05:10:13.229689 16009 master.cpp:5793] Processing ACKNOWLEDGE call 210d1a55-2211-4a14-b9db-0c9f0a6be2b6 for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: I1125 05:10:13.229790 16009 master.cpp:9629] Removing task 0 with resources cpus(allocated: *):2 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.230234 16000 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.230340 16000 task_status_update_manager.cpp:538] Cleaning up status update stream for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.230727 16003 slave.cpp:3848] Task status update manager successfully handled status update acknowledgement (UUID: 210d1a55-2211-4a14-b9db-0c9f0a6be2b6) for task 0 of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.230772 16003 slave.cpp:8428] Completing task 0
3: I1125 05:10:13.278318 16014 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.090736ms
3: I1125 05:10:13.278828 15996 master.cpp:8142] Sending 1 offers to framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.279431 15998 sched.cpp:921] Scheduler::resourceOffers took 67280ns
3: I1125 05:10:13.279796 15991 sched.cpp:2009] Asked to stop the driver
3: I1125 05:10:13.279901 15999 sched.cpp:1191] Stopping framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.280184 16008 master.cpp:8819] Processing TEARDOWN call for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.280212 16008 master.cpp:8831] Removing framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.280225 16008 master.cpp:3333] Deactivating framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 (default) at scheduler-e9719839-bdea-4fca-99e1-2ad8a56160af@172.17.0.7:42876
3: I1125 05:10:13.280385 16010 hierarchical.cpp:419] Deactivated framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.280804 16008 master.cpp:10063] Removing offer eb94d6e6-00a3-4c13-81ff-367343f7aa23-O1
3: I1125 05:10:13.281061 15994 slave.cpp:3270] Asked to shut down framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 by master@172.17.0.7:42876
3: I1125 05:10:13.281064 16008 master.cpp:9658] Removing executor 'default' with resources [] of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.281129 15994 slave.cpp:3295] Shutting down framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.281199 15994 slave.cpp:5923] Shutting down executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 at executor(96)@172.17.0.7:42876
3: I1125 05:10:13.281349 15998 exec.cpp:435] Executor asked to shutdown
3: I1125 05:10:13.281394 15998 exec.cpp:450] Executor::shutdown took 22011ns
3: I1125 05:10:13.281497 16006 hierarchical.cpp:1132] Recovered cpus(allocated: *):2; disk(allocated: *):367489; ports(allocated: *):[31000-32000] (total: cpus:2; disk:367489; ports:[31000-32000], allocated: {}) on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 from framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.281569 16011 slave.cpp:5179] Got exited event for executor(96)@172.17.0.7:42876
3: I1125 05:10:13.281817 16015 slave.cpp:5588] Executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 exited with status 0
3: I1125 05:10:13.281908 16015 slave.cpp:5692] Cleaning up executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 at executor(96)@172.17.0.7:42876
3: I1125 05:10:13.282021 15999 hierarchical.cpp:358] Removed framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: W1125 05:10:13.282147 16009 master.cpp:7480] Ignoring unknown exited executor 'default' of framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000 on agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.282229 16014 gc.cpp:90] Scheduling '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default/runs/3b49f98a-95d8-4c24-b01e-e1ea9a804fe7' for gc 6.99999673502222days in the future
3: I1125 05:10:13.282356 16015 slave.cpp:5799] Cleaning up framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.282415 16014 gc.cpp:90] Scheduling '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000/executors/default' for gc 6.99999673283556days in the future
3: I1125 05:10:13.282454 15995 task_status_update_manager.cpp:289] Closing task status update streams for framework eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000
3: I1125 05:10:13.282543 16015 slave.cpp:883] Agent terminating
3: I1125 05:10:13.282574 16014 gc.cpp:90] Scheduling '/tmp/MasterAllocatorTest_0_CpusOnlyOfferedAndTaskLaunched_mHISie/slaves/eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0/frameworks/eb94d6e6-00a3-4c13-81ff-367343f7aa23-0000' for gc 6.99999673084444days in the future
3: I1125 05:10:13.282730 15998 master.cpp:1311] Agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2) disconnected
3: I1125 05:10:13.282752 15998 master.cpp:3370] Disconnecting agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.282796 15998 master.cpp:3389] Deactivating agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 at slave(268)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.282894 16006 hierarchical.cpp:697] Agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0 deactivated
3: I1125 05:10:13.286535 15997 master.cpp:1153] Master terminating
3: I1125 05:10:13.287156 16000 hierarchical.cpp:633] Removed agent eb94d6e6-00a3-4c13-81ff-367343f7aa23-S0
3: [ OK ] MasterAllocatorTest/0.CpusOnlyOfferedAndTaskLaunched (124 ms)
3: [ RUN ] MasterAllocatorTest/0.MemoryOnlyOfferedAndTaskLaunched
3: I1125 05:10:13.297780 15991 cluster.cpp:162] Creating default 'local' authorizer
3: I1125 05:10:13.301786 15993 master.cpp:448] Master c736c074-b4a6-47c1-8141-4c00225d8a4f (5772de05b3f2) started on 172.17.0.7:42876
3: I1125 05:10:13.301815 15993 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --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/caaxRO/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/caaxRO/master" --zk_session_timeout="10secs"
3: I1125 05:10:13.302129 15993 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1125 05:10:13.302137 15993 master.cpp:505] Master only allowing authenticated agents to register
3: I1125 05:10:13.302141 15993 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1125 05:10:13.302146 15993 credentials.hpp:37] Loading credentials for authentication from '/tmp/caaxRO/credentials'
3: I1125 05:10:13.302464 15993 master.cpp:555] Using default 'crammd5' authenticator
3: I1125 05:10:13.302639 15993 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1125 05:10:13.302804 15993 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1125 05:10:13.302922 15993 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1125 05:10:13.303033 15993 master.cpp:634] Authorization enabled
3: I1125 05:10:13.303257 15992 whitelist_watcher.cpp:77] No whitelist given
3: I1125 05:10:13.303282 15996 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1125 05:10:13.306329 15995 master.cpp:2215] Elected as the leading master!
3: I1125 05:10:13.306363 15995 master.cpp:1695] Recovering from registrar
3: I1125 05:10:13.306533 16010 registrar.cpp:347] Recovering registrar
3: I1125 05:10:13.307199 16010 registrar.cpp:391] Successfully fetched the registry (0B) in 624128ns
3: I1125 05:10:13.307322 16010 registrar.cpp:495] Applied 1 operations in 37673ns; attempting to update the registry
3: I1125 05:10:13.307899 16010 registrar.cpp:552] Successfully updated the registry in 517888ns
3: I1125 05:10:13.308015 16010 registrar.cpp:424] Successfully recovered registrar
3: I1125 05:10:13.308476 16008 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: I1125 05:10:13.308466 16013 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: W1125 05:10:13.316205 15991 process.cpp:2756] Attempted to spawn already running process files@172.17.0.7:42876
3: I1125 05:10:13.316608 15991 cluster.cpp:448] Creating default 'local' authorizer
3: I1125 05:10:13.319615 16000 slave.cpp:262] Mesos agent started on (269)@172.17.0.7:42876
3: I1125 05:10:13.319638 16000 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/store/appc" --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/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/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/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/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/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/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/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/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:0;mem:200" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC" --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/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_5L0PII" --zk_session_timeout="10secs"
3: I1125 05:10:13.320132 16000 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/credential'
3: I1125 05:10:13.320354 16000 slave.cpp:295] Agent using credential for: test-principal
3: I1125 05:10:13.320379 16000 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_0CXaAC/http_credentials'
3: W1125 05:10:13.320500 15991 process.cpp:2756] Attempted to spawn already running process version@172.17.0.7:42876
3: I1125 05:10:13.320660 16000 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1125 05:10:13.320878 16000 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1125 05:10:13.321964 15991 sched.cpp:232] Version: 1.5.0
3: I1125 05:10:13.322165 16000 slave.cpp:593] Agent resources: [{"name":"mem","scalar":{"value":200.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1125 05:10:13.322377 16000 slave.cpp:601] Agent attributes: [ ]
3: I1125 05:10:13.322391 16000 slave.cpp:610] Agent hostname: 5772de05b3f2
3: I1125 05:10:13.322562 15992 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1125 05:10:13.322681 15996 sched.cpp:336] New master detected at master@172.17.0.7:42876
3: I1125 05:10:13.322805 15996 sched.cpp:396] Authenticating with master master@172.17.0.7:42876
3: I1125 05:10:13.322825 15996 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1125 05:10:13.323117 15993 authenticatee.cpp:121] Creating new client SASL connection
3: I1125 05:10:13.323451 15995 master.cpp:8312] Authenticating scheduler-ad76f451-0419-48bf-91c6-e6202fa1dcac@172.17.0.7:42876
3: I1125 05:10:13.323575 16012 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(551)@172.17.0.7:42876
3: I1125 05:10:13.323818 15997 authenticator.cpp:98] Creating new server SASL connection
3: I1125 05:10:13.324059 16011 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1125 05:10:13.324090 16011 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1125 05:10:13.324218 16011 authenticator.cpp:204] Received SASL authentication start
3: I1125 05:10:13.324286 16011 authenticator.cpp:326] Authentication requires more steps
3: I1125 05:10:13.324426 16015 authenticatee.cpp:259] Received SASL authentication step
3: I1125 05:10:13.324558 16006 authenticator.cpp:232] Received SASL authentication step
3: I1125 05:10:13.324594 16006 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5772de05b3f2' server FQDN: '5772de05b3f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1125 05:10:13.324604 15999 state.cpp:64] Recovering state from '/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_5L0PII/meta'
3: I1125 05:10:13.324610 16006 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1125 05:10:13.324664 16006 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1125 05:10:13.324693 16006 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5772de05b3f2' server FQDN: '5772de05b3f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1125 05:10:13.324708 16006 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1125 05:10:13.324717 16006 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1125 05:10:13.324738 16006 authenticator.cpp:318] Authentication success
3: I1125 05:10:13.324851 16007 authenticatee.cpp:299] Authentication success
3: I1125 05:10:13.324949 16001 master.cpp:8342] Successfully authenticated principal 'test-principal' at scheduler-ad76f451-0419-48bf-91c6-e6202fa1dcac@172.17.0.7:42876
3: I1125 05:10:13.324960 15992 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1125 05:10:13.324996 16008 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(551)@172.17.0.7:42876
3: I1125 05:10:13.325215 16007 sched.cpp:502] Successfully authenticated with master master@172.17.0.7:42876
3: I1125 05:10:13.325240 16007 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.7:42876
3: I1125 05:10:13.325343 15995 slave.cpp:6493] Finished recovery
3: I1125 05:10:13.325361 16007 sched.cpp:857] Will retry registration in 1.908278252secs if necessary
3: I1125 05:10:13.325536 16012 master.cpp:2963] Received SUBSCRIBE call for framework 'default' at scheduler-ad76f451-0419-48bf-91c6-e6202fa1dcac@172.17.0.7:42876
3: I1125 05:10:13.325628 16012 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1125 05:10:13.326159 16005 master.cpp:3043] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1125 05:10:13.326238 16000 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1125 05:10:13.326254 16015 slave.cpp:1007] New master detected at master@172.17.0.7:42876
3: I1125 05:10:13.326387 16015 slave.cpp:1042] Detecting new master
3: I1125 05:10:13.326838 16013 sched.cpp:751] Framework registered with c736c074-b4a6-47c1-8141-4c00225d8a4f-0000
3: I1125 05:10:13.326884 16013 sched.cpp:765] Scheduler::registered took 23369ns
3: I1125 05:10:13.327044 15999 hierarchical.cpp:306] Added framework c736c074-b4a6-47c1-8141-4c00225d8a4f-0000
3: I1125 05:10:13.327270 15999 hierarchical.cpp:1457] Performed allocation for 0 agents in 83413ns
3: I1125 05:10:13.327616 15994 slave.cpp:1069] Authenticating with master master@172.17.0.7:42876
3: I1125 05:10:13.327673 15994 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1125 05:10:13.327925 15992 authenticatee.cpp:121] Creating new client SASL connection
3: I1125 05:10:13.328199 15993 master.cpp:8312] Authenticating slave(269)@172.17.0.7:42876
3: I1125 05:10:13.328305 16001 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(552)@172.17.0.7:42876
3: I1125 05:10:13.328534 16003 authenticator.cpp:98] Creating new server SASL connection
3: I1125 05:10:13.328735 16008 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1125 05:10:13.328758 16008 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1125 05:10:13.328858 16004 authenticator.cpp:204] Received SASL authentication start
3: I1125 05:10:13.328910 16004 authenticator.cpp:326] Authentication requires more steps
3: I1125 05:10:13.329008 16007 authenticatee.cpp:259] Received SASL authentication step
3: I1125 05:10:13.329156 15996 authenticator.cpp:232] Received SASL authentication step
3: I1125 05:10:13.329185 15996 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5772de05b3f2' server FQDN: '5772de05b3f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1125 05:10:13.329196 15996 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1125 05:10:13.329234 15996 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1125 05:10:13.329253 15996 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5772de05b3f2' server FQDN: '5772de05b3f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1125 05:10:13.329260 15996 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1125 05:10:13.329267 15996 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1125 05:10:13.329278 15996 authenticator.cpp:318] Authentication success
3: I1125 05:10:13.329360 16009 authenticatee.cpp:299] Authentication success
3: I1125 05:10:13.329422 16012 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(269)@172.17.0.7:42876
3: I1125 05:10:13.329457 15995 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(552)@172.17.0.7:42876
3: I1125 05:10:13.329677 16002 slave.cpp:1161] Successfully authenticated with master master@172.17.0.7:42876
3: I1125 05:10:13.329944 16002 slave.cpp:1685] Will retry registration in 4.554853ms if necessary
3: I1125 05:10:13.330168 16015 master.cpp:6036] Received register agent message from slave(269)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.330310 16015 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1125 05:10:13.330750 16006 master.cpp:6098] Authorized registration of agent at slave(269)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.330878 16006 master.cpp:6191] Registering agent at slave(269)@172.17.0.7:42876 (5772de05b3f2) with id c736c074-b4a6-47c1-8141-4c00225d8a4f-S0
3: I1125 05:10:13.331344 16005 registrar.cpp:495] Applied 1 operations in 68504ns; attempting to update the registry
3: I1125 05:10:13.332000 16005 registrar.cpp:552] Successfully updated the registry in 590080ns
3: I1125 05:10:13.332206 15994 master.cpp:6240] Admitted agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2)
3: I1125 05:10:13.332954 15994 master.cpp:6276] Registered agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2) with mem:200; disk:367489; ports:[31000-32000]
3: I1125 05:10:13.332996 15993 slave.cpp:1207] Registered with master master@172.17.0.7:42876; given agent ID c736c074-b4a6-47c1-8141-4c00225d8a4f-S0
3: I1125 05:10:13.333096 16008 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1125 05:10:13.333268 16001 hierarchical.cpp:600] Added agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 (5772de05b3f2) with mem:200; disk:367489; ports:[31000-32000] (allocated: {})
3: I1125 05:10:13.333416 15993 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/MasterAllocatorTest_0_MemoryOnlyOfferedAndTaskLaunched_5L0PII/meta/slaves/c736c074-b4a6-47c1-8141-4c00225d8a4f-S0/slave.info'
3: I1125 05:10:13.333874 15993 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1125 05:10:13.334076 16007 master.cpp:7091] Received update of agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2) with total oversubscribed resources {}
3: I1125 05:10:13.334261 16007 master.cpp:7109] Ignoring update on agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2) as it reports no changes
3: I1125 05:10:13.334553 16001 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.100759ms
3: I1125 05:10:13.334961 16009 master.cpp:8142] Sending 1 offers to framework c736c074-b4a6-47c1-8141-4c00225d8a4f-0000 (default) at scheduler-ad76f451-0419-48bf-91c6-e6202fa1dcac@172.17.0.7:42876
3: I1125 05:10:13.336066 15996 sched.cpp:921] Scheduler::resourceOffers took 680338ns
3: I1125 05:10:13.337239 15997 master.cpp:10063] Removing offer c736c074-b4a6-47c1-8141-4c00225d8a4f-O0
3: I1125 05:10:13.337378 15997 master.cpp:4230] Processing ACCEPT call for offers: [ c736c074-b4a6-47c1-8141-4c00225d8a4f-O0 ] on agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2) for framework c736c074-b4a6-47c1-8141-4c00225d8a4f-0000 (default) at scheduler-ad76f451-0419-48bf-91c6-e6202fa1dcac@172.17.0.7:42876
3: I1125 05:10:13.337504 15997 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 0
3: W1125 05:10:13.339071 16011 validation.cpp:1391] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1125 05:10:13.339103 16011 validation.cpp:1403] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1125 05:10:13.339459 16011 master.cpp:10814] Adding task 0 with resources mem(allocated: *):200 on agent c736c074-b4a6-47c1-8141-4c00225d8a4f-S0 at slave(269)@172.17.0.7:42876 (5772de05b3f2)
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511584660-8447
Error response from daemon: conflict: unable to remove repository reference "mesos-1511584660-8447" (must force) - container 5772de05b3f2 is using its referenced image 540c79bdb299
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4498
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,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4498/display/redirect?page=changes>