See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1283/>
------------------------------------------ [...truncated 53668 lines...] I0628 05:52:42.398442 4440 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.400142 4440 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.400923 4446 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-2 on slave 201306280552-453759884-56904-4314-1 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.401890 4446 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.401981 4450 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.449682 4440 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.451323 4440 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.452591 4440 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 2.939593ms I0628 05:52:42.452713 4439 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.454818 4439 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.455713 4448 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-3 on slave 201306280552-453759884-56904-4314-1 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.456562 4448 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.456637 4446 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.503993 4439 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.505126 4439 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.506307 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 2.338154ms I0628 05:52:42.506415 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.508946 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.509860 4443 master.cpp:1239] Sending 2 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.510926 4433 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000' I0628 05:52:42.510946 4314 master.cpp:385] Master terminating I0628 05:52:42.512903 4314 master.cpp:207] Shutting down master I0628 05:52:42.512953 4437 slave.cpp:484] Slave asked to shut down by [email protected]:56904 I0628 05:52:42.514961 4437 slave.cpp:439] Slave terminating I0628 05:52:42.513991 4314 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.514076 4431 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.513051 4445 slave.cpp:484] Slave asked to shut down by [email protected]:56904 I0628 05:52:42.516532 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.517346 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.517894 4445 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by [email protected]:56904 I0628 05:52:42.518484 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.519191 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=55861 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-1 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.519511 4445 slave.cpp:1136] Shutting down framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.520926 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=55861 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.521272 4445 slave.cpp:2327] Shutting down executor 'default' of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.521700 4431 hierarchical_allocator_process.hpp:367] Removed framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.522079 4429 exec.cpp:323] Executor asked to shutdown I0628 05:52:42.522079 4445 slave.cpp:439] Slave terminating I0628 05:52:42.522404 4431 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-1 I0628 05:52:42.523087 4445 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by @0.0.0.0:0 I0628 05:52:42.523418 4431 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-0 W0628 05:52:42.523736 4445 slave.cpp:1132] Ignoring shutdown framework 201306280552-453759884-56904-4314-0000 because it is terminating [ OK ] AllocatorTest/0.SlaveAdded (182 ms) [ RUN ] AllocatorTest/0.TaskFinished I0628 05:52:42.525843 4450 master.cpp:228] Master started on 140.211.11.27:56904 I0628 05:52:42.526249 4450 master.cpp:243] Master ID: 201306280552-453759884-56904-4314 W0628 05:52:42.526801 4445 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 05:52:42.527009 4450 master.cpp:526] Elected as master! I0628 05:52:42.527076 4432 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:56904 I0628 05:52:42.527164 4430 slave.cpp:112] Slave started on 489)@140.211.11.27:56904 I0628 05:52:42.527753 4435 sched.cpp:177] New master at [email protected]:56904 I0628 05:52:42.528537 4430 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=55861 I0628 05:52:42.528818 4435 master.cpp:569] Registering framework 201306280552-453759884-56904-4314-0000 at scheduler(444)@140.211.11.27:56904 I0628 05:52:42.529340 4445 sched.cpp:222] Framework registered with 201306280552-453759884-56904-4314-0000 I0628 05:52:42.529384 4438 hierarchical_allocator_process.hpp:327] Added framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.529647 4430 slave.cpp:528] New master detected at [email protected]:56904 I0628 05:52:42.529921 4438 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 05:52:42.530074 4430 slave.cpp:543] Postponing registration until recovery is complete I0628 05:52:42.530087 4447 status_update_manager.cpp:155] New master detected at [email protected]:56904 I0628 05:52:42.530354 4438 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 416.212us I0628 05:52:42.530519 4430 slave.cpp:389] Finished recovery I0628 05:52:42.531028 4442 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(489)@140.211.11.27:56904 I0628 05:52:42.531160 4442 master.cpp:1851] Adding slave 201306280552-453759884-56904-4314-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=55861 I0628 05:52:42.531350 4433 slave.cpp:588] Registered with master [email protected]:56904; given slave ID 201306280552-453759884-56904-4314-0 I0628 05:52:42.531523 4436 hierarchical_allocator_process.hpp:449] Added slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=55861 (and cpus=3; mem=1024; ports=[31000-32000]; disk=55861 available) I0628 05:52:42.531783 4436 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.532112 4436 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306280552-453759884-56904-4314-0 in 338.903us I0628 05:52:42.532145 4433 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.532480 4433 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.532933 4437 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-0 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.533151 4437 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.533476 4437 master.cpp:1591] Launching task 0 of framework 201306280552-453759884-56904-4314-0000 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) I0628 05:52:42.533833 4446 slave.cpp:738] Got assigned task 0 for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.533828 4437 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.534293 4446 slave.cpp:836] Launching task 0 for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.534368 4437 master.cpp:1591] Launching task 1 of framework 201306280552-453759884-56904-4314-0000 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) I0628 05:52:42.534868 4437 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.534937 4444 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.535433 4444 hierarchical_allocator_process.hpp:569] Framework 201306280552-453759884-56904-4314-0000 filtered slave 201306280552-453759884-56904-4314-0 for 5secs I0628 05:52:42.536211 4446 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_vGFxvW/slaves/201306280552-453759884-56904-4314-0/frameworks/201306280552-453759884-56904-4314-0000/executors/default/runs/e886094d-4cee-4016-9e7c-bf2032980337' I0628 05:52:42.536659 4446 slave.cpp:947] Queuing task '0' for executor default of framework '201306280552-453759884-56904-4314-0000 I0628 05:52:42.536924 4434 exec.cpp:170] Executor started at: executor(180)@140.211.11.27:56904 with pid 4314 I0628 05:52:42.536964 4446 slave.cpp:738] Got assigned task 1 for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.537569 4446 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_vGFxvW/slaves/201306280552-453759884-56904-4314-0/frameworks/201306280552-453759884-56904-4314-0000/executors/default/runs/e886094d-4cee-4016-9e7c-bf2032980337' I0628 05:52:42.537880 4446 slave.cpp:1394] Got registration for executor 'default' of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.538264 4446 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.538295 4435 exec.cpp:194] Executor registered on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.538521 4446 slave.cpp:836] Launching task 1 for framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.538717 4435 exec.cpp:258] Executor asked to run task '0' I0628 05:52:42.538933 4446 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.540534 4435 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.541013 4435 exec.cpp:258] Executor asked to run task '1' I0628 05:52:42.541051 4431 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904 I0628 05:52:42.542392 4431 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false I0628 05:52:42.542956 4431 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.543453 4431 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 to [email protected]:56904 I0628 05:52:42.543648 4435 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.543903 4439 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 0 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_RUNNING I0628 05:52:42.544093 4437 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.544960 4437 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904 I0628 05:52:42.545371 4437 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904 I0628 05:52:42.545831 4444 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false I0628 05:52:42.546172 4435 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.546406 4444 status_update_manager.cpp:360] Received status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.546458 4433 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.546576 4435 exec.cpp:290] Executor received status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.546871 4444 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 to [email protected]:56904 I0628 05:52:42.547209 4433 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904 I0628 05:52:42.547924 4436 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 0 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_FINISHED I0628 05:52:42.548326 4444 exec.cpp:290] Executor received status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.548329 4433 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904 I0628 05:52:42.548702 4436 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.549530 4433 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.549567 4438 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false I0628 05:52:42.550365 4444 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.550639 4438 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.551269 4438 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 to [email protected]:56904 I0628 05:52:42.551647 4438 status_update_manager.cpp:360] Received status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.551683 4433 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 1 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_RUNNING I0628 05:52:42.551702 4443 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.551852 4438 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.552580 4443 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904 I0628 05:52:42.553500 4438 exec.cpp:290] Executor received status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.553524 4443 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.553526 4450 status_update_manager.cpp:360] Received status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000 E0628 05:52:42.554319 4443 slave.cpp:1366] Status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of unknown executor I0628 05:52:42.556164 4443 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.578306 4445 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.579953 4445 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 1.71038ms I0628 05:52:42.580098 4438 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.582033 4438 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.583438 4449 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000' I0628 05:52:42.584180 4429 master.cpp:385] Master terminating I0628 05:52:42.585289 4314 master.cpp:207] Shutting down master I0628 05:52:42.585248 4433 slave.cpp:484] Slave asked to shut down by [email protected]:56904 I0628 05:52:42.586233 4314 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.586288 4429 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.587049 4433 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by [email protected]:56904 I0628 05:52:42.587661 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.588259 4429 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.588919 4433 slave.cpp:1136] Shutting down framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.590335 4429 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=55861 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.590951 4433 slave.cpp:2327] Shutting down executor 'default' of framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.591647 4429 hierarchical_allocator_process.hpp:367] Removed framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.592386 4433 slave.cpp:439] Slave terminating I0628 05:52:42.592494 4440 exec.cpp:323] Executor asked to shutdown I0628 05:52:42.593169 4429 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.593752 4433 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by @0.0.0.0:0 W0628 05:52:42.595379 4433 slave.cpp:1132] Ignoring shutdown framework 201306280552-453759884-56904-4314-0000 because it is terminating [ OK ] AllocatorTest/0.TaskFinished (72 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0628 05:52:42.598083 4435 master.cpp:228] Master started on 140.211.11.27:56904 I0628 05:52:42.598711 4435 master.cpp:243] Master ID: 201306280552-453759884-56904-4314 I0628 05:52:42.598953 4442 slave.cpp:112] Slave started on 490)@140.211.11.27:56904 I0628 05:52:42.599411 4435 master.cpp:526] Elected as master! I0628 05:52:42.599715 4439 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:56904 I0628 05:52:42.599776 4442 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=55861 I0628 05:52:42.600050 4451 sched.cpp:177] New master at [email protected]:56904 I0628 05:52:42.601346 4442 slave.cpp:528] New master detected at [email protected]:56904 I0628 05:52:42.600482 4439 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0628 05:52:42.601997 4439 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 05:52:42.601701 4434 status_update_manager.cpp:155] New master detected at [email protected]:56904 I0628 05:52:42.601711 4431 master.cpp:569] Registering framework 201306280552-453759884-56904-4314-0000 at scheduler(445)@140.211.11.27:56904 I0628 05:52:42.601687 4442 slave.cpp:543] Postponing registration until recovery is complete I0628 05:52:42.603212 4442 slave.cpp:389] Finished recovery I0628 05:52:42.602959 4452 sched.cpp:222] Framework registered with 201306280552-453759884-56904-4314-0000 I0628 05:52:42.602957 4431 master.cpp:556] Framework 201306280552-453759884-56904-4314-0000 (scheduler(445)@140.211.11.27:56904) already registered, resending acknowledgement I0628 05:52:42.602335 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 339.758us I0628 05:52:42.604017 4433 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0628 05:52:42.604022 4431 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(490)@140.211.11.27:56904 I0628 05:52:42.604274 4439 hierarchical_allocator_process.hpp:327] Added framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.604686 4431 master.cpp:1851] Adding slave 201306280552-453759884-56904-4314-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=55861 I0628 05:52:42.604892 4439 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 05:52:42.605147 4446 slave.cpp:588] Registered with master [email protected]:56904; given slave ID 201306280552-453759884-56904-4314-0 I0628 05:52:42.605219 4431 master.cpp:880] Slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) already registered, resending acknowledgement I0628 05:52:42.605247 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 355.934us W0628 05:52:42.605603 4443 slave.cpp:612] Already registered with master [email protected]:56904 I0628 05:52:42.605782 4439 hierarchical_allocator_process.hpp:449] Added slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=55861 (and cpus=2; mem=1024; ports=[31000-32000]; disk=55861 available) I0628 05:52:42.610342 4431 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 16.097us I0628 05:52:42.620462 4446 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 14.43us I0628 05:52:42.630614 4433 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 64.541us I0628 05:52:42.640864 4451 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 18.292us I0628 05:52:42.642068 4451 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, hemera.apache.org } I0628 05:52:42.650827 4441 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.652202 4441 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 1.404095ms I0628 05:52:42.652241 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.654523 4443 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.660895 4314 master.cpp:385] Master terminating I0628 05:52:42.660904 4443 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000' I0628 05:52:42.662155 4314 master.cpp:207] Shutting down master I0628 05:52:42.662180 4445 slave.cpp:484] Slave asked to shut down by [email protected]:56904 I0628 05:52:42.664342 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 I0628 05:52:42.664460 4450 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000 I0628 05:52:42.665169 4445 slave.cpp:439] Slave terminating I0628 05:52:42.666685 4450 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=55861 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000 [ OK ] AllocatorTest/0.WhitelistSlave (71 ms) [----------] 9 tests from AllocatorTest/0 (529 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0628 05:52:42.668669 4439 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.670743 4453 process.cpp:878] Socket closed while receiving I0628 05:52:42.671967 4446 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.674150 4453 process.cpp:878] Socket closed while receiving I0628 05:52:42.675279 4440 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.676405 4453 process.cpp:878] Socket closed while receiving I0628 05:52:42.677888 4435 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.679604 4453 process.cpp:878] Socket closed while receiving I0628 05:52:42.680681 4446 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.681702 4453 process.cpp:878] Socket closed while receiving I0628 05:52:42.682587 4438 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 05:52:42.683797 4453 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (16 ms) [----------] 1 test from LoggingTest (17 ms total) [----------] 5 tests from CgroupsCpusetTest [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (0 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (0 ms) [----------] 3 tests from FsTest (0 ms total) [----------] Global test environment tear-down [==========] 168 tests from 32 test cases ran. (17567 ms total) [ PASSED ] 168 tests. make[3]: *** [check-local] Error 1 make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make: *** [check-recursive] Error 1 I0628 05:52:45.478795 10801 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
