See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1281/>
------------------------------------------ [...truncated 53363 lines...] I0627 23:00:57.246816 23952 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.248620 23952 master.cpp:1239] Sending 1 offers to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.249369 23952 master.cpp:1472] Processing reply for offer 201306272300-453759884-48144-23872-3 on slave 201306272300-453759884-48144-23872-1 (hemera.apache.org) for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.250373 23952 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.250463 23941 hierarchical_allocator_process.hpp:526] Framework 201306272300-453759884-48144-23872-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=56265 unused on slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.298138 23936 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.298956 23936 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-1 to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.300010 23936 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 1.917434ms I0627 23:00:57.300215 23945 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.301866 23945 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.302561 23945 master.cpp:1239] Sending 2 offers to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.303519 23872 master.cpp:385] Master terminating I0627 23:00:57.303522 23948 sched.cpp:427] Stopping framework '201306272300-453759884-48144-23872-0000' I0627 23:00:57.303859 23872 master.cpp:207] Shutting down master I0627 23:00:57.303899 23934 slave.cpp:484] Slave asked to shut down by [email protected]:48144 I0627 23:00:57.303989 23944 slave.cpp:484] Slave asked to shut down by [email protected]:48144 I0627 23:00:57.305140 23872 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.305253 23945 hierarchical_allocator_process.hpp:412] Deactivated framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.305960 23934 slave.cpp:439] Slave terminating I0627 23:00:57.306566 23944 slave.cpp:1111] Asked to shut down framework 201306272300-453759884-48144-23872-0000 by [email protected]:48144 I0627 23:00:57.307167 23872 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.307811 23945 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.308619 23944 slave.cpp:1136] Shutting down framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.308984 23872 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.309412 23945 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=56265 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=56265) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.309736 23944 slave.cpp:2327] Shutting down executor 'default' of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.310247 23947 exec.cpp:323] Executor asked to shutdown I0627 23:00:57.310250 23944 slave.cpp:439] Slave terminating I0627 23:00:57.310246 23945 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=56265 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=56265) on slave 201306272300-453759884-48144-23872-1 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.311045 23944 slave.cpp:1111] Asked to shut down framework 201306272300-453759884-48144-23872-0000 by @0.0.0.0:0 I0627 23:00:57.311375 23945 hierarchical_allocator_process.hpp:367] Removed framework 201306272300-453759884-48144-23872-0000 W0627 23:00:57.311724 23944 slave.cpp:1132] Ignoring shutdown framework 201306272300-453759884-48144-23872-0000 because it is terminating I0627 23:00:57.312108 23945 hierarchical_allocator_process.hpp:477] Removed slave 201306272300-453759884-48144-23872-1 I0627 23:00:57.313161 23945 hierarchical_allocator_process.hpp:477] Removed slave 201306272300-453759884-48144-23872-0 [ OK ] AllocatorTest/0.SlaveAdded (175 ms) [ RUN ] AllocatorTest/0.TaskFinished I0627 23:00:57.313992 23936 master.cpp:228] Master started on 140.211.11.27:48144 I0627 23:00:57.314226 23936 master.cpp:243] Master ID: 201306272300-453759884-48144-23872 W0627 23:00:57.314441 23951 master.cpp:83] No whitelist given. Advertising offers for all slaves I0627 23:00:57.314543 23950 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:48144 I0627 23:00:57.314558 23936 master.cpp:526] Elected as master! I0627 23:00:57.314591 23944 slave.cpp:112] Slave started on 489)@140.211.11.27:48144 I0627 23:00:57.314957 23944 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=56265 I0627 23:00:57.315093 23950 sched.cpp:177] New master at [email protected]:48144 I0627 23:00:57.315570 23939 master.cpp:569] Registering framework 201306272300-453759884-48144-23872-0000 at scheduler(444)@140.211.11.27:48144 I0627 23:00:57.315656 23944 slave.cpp:528] New master detected at [email protected]:48144 I0627 23:00:57.315770 23944 slave.cpp:543] Postponing registration until recovery is complete I0627 23:00:57.315784 23946 status_update_manager.cpp:155] New master detected at [email protected]:48144 I0627 23:00:57.315879 23944 slave.cpp:389] Finished recovery I0627 23:00:57.316059 23935 hierarchical_allocator_process.hpp:327] Added framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.315999 23957 sched.cpp:222] Framework registered with 201306272300-453759884-48144-23872-0000 I0627 23:00:57.316211 23935 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0627 23:00:57.316227 23956 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(489)@140.211.11.27:48144 I0627 23:00:57.316406 23935 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 196.562us I0627 23:00:57.316525 23956 master.cpp:1851] Adding slave 201306272300-453759884-48144-23872-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=56265 I0627 23:00:57.316826 23948 slave.cpp:588] Registered with master [email protected]:48144; given slave ID 201306272300-453759884-48144-23872-0 I0627 23:00:57.317054 23944 hierarchical_allocator_process.hpp:449] Added slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=56265 (and cpus=3; mem=1024; ports=[31000-32000]; disk=56265 available) I0627 23:00:57.317212 23944 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.317553 23944 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306272300-453759884-48144-23872-0 in 349.688us I0627 23:00:57.317618 23948 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.318022 23948 master.cpp:1239] Sending 1 offers to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.318505 23943 master.cpp:1472] Processing reply for offer 201306272300-453759884-48144-23872-0 on slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.318632 23943 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.318945 23943 master.cpp:1591] Launching task 0 of framework 201306272300-453759884-48144-23872-0000 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) I0627 23:00:57.319293 23943 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.319341 23948 slave.cpp:738] Got assigned task 0 for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.319466 23943 master.cpp:1591] Launching task 1 of framework 201306272300-453759884-48144-23872-0000 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) I0627 23:00:57.319811 23948 slave.cpp:836] Launching task 0 for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.320020 23943 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.320145 23946 hierarchical_allocator_process.hpp:526] Framework 201306272300-453759884-48144-23872-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=56265 unused on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.320775 23946 hierarchical_allocator_process.hpp:569] Framework 201306272300-453759884-48144-23872-0000 filtered slave 201306272300-453759884-48144-23872-0 for 5secs I0627 23:00:57.321213 23948 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_nQj7Et/slaves/201306272300-453759884-48144-23872-0/frameworks/201306272300-453759884-48144-23872-0000/executors/default/runs/dd1728e3-3fa8-486b-8ed1-3e2f8b95c464' I0627 23:00:57.321601 23948 slave.cpp:947] Queuing task '0' for executor default of framework '201306272300-453759884-48144-23872-0000 I0627 23:00:57.322348 23948 slave.cpp:738] Got assigned task 1 for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.322468 23937 exec.cpp:170] Executor started at: executor(180)@140.211.11.27:48144 with pid 23872 I0627 23:00:57.322494 23948 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_nQj7Et/slaves/201306272300-453759884-48144-23872-0/frameworks/201306272300-453759884-48144-23872-0000/executors/default/runs/dd1728e3-3fa8-486b-8ed1-3e2f8b95c464' I0627 23:00:57.322883 23948 slave.cpp:836] Launching task 1 for framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.323021 23948 slave.cpp:947] Queuing task '1' for executor default of framework '201306272300-453759884-48144-23872-0000 I0627 23:00:57.323304 23948 slave.cpp:1394] Got registration for executor 'default' of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.323675 23948 slave.cpp:1509] Flushing queued task 1 for executor 'default' of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.323696 23944 exec.cpp:194] Executor registered on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.324127 23948 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.324627 23944 exec.cpp:258] Executor asked to run task '1' I0627 23:00:57.325449 23944 exec.cpp:258] Executor asked to run task '0' I0627 23:00:57.326576 23944 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.327021 23942 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 from executor(180)@140.211.11.27:48144 I0627 23:00:57.327699 23942 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false I0627 23:00:57.328310 23942 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.328388 23944 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.328791 23942 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 to [email protected]:48144 I0627 23:00:57.329220 23957 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 from executor(180)@140.211.11.27:48144 I0627 23:00:57.329872 23948 master.cpp:1022] Status update from slave(489)@140.211.11.27:48144: task 1 of framework 201306272300-453759884-48144-23872-0000 is now in state TASK_RUNNING I0627 23:00:57.330283 23957 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.330322 23937 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false I0627 23:00:57.330845 23944 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.331014 23957 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71) for task 1 of framework 201306272300-453759884-48144-23872-0000 to executor(180)@140.211.11.27:48144 I0627 23:00:57.331529 23937 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.332324 23942 exec.cpp:290] Executor received status update acknowledgement 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.332360 23957 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 from executor(180)@140.211.11.27:48144 I0627 23:00:57.332690 23937 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 to [email protected]:48144 I0627 23:00:57.334482 23946 master.cpp:1022] Status update from slave(489)@140.211.11.27:48144: task 0 of framework 201306272300-453759884-48144-23872-0000 is now in state TASK_RUNNING I0627 23:00:57.334584 23937 status_update_manager.cpp:360] Received status update acknowledgement 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.334590 23951 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.334888 23937 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 with checkpoint=false I0627 23:00:57.335178 23951 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: ce08e278-8d24-4172-9bb6-c13ced8a79b5) for task 0 of framework 201306272300-453759884-48144-23872-0000 to executor(180)@140.211.11.27:48144 I0627 23:00:57.335438 23937 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 to [email protected]:48144 I0627 23:00:57.335887 23951 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 3a1e08a3-c8b5-403b-80a8-2b69bac5ad71 for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.335893 23940 exec.cpp:290] Executor received status update acknowledgement ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.336331 23937 status_update_manager.cpp:360] Received status update acknowledgement ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.336382 23946 master.cpp:1022] Status update from slave(489)@140.211.11.27:48144: task 1 of framework 201306272300-453759884-48144-23872-0000 is now in state TASK_FINISHED I0627 23:00:57.336535 23951 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.337544 23946 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.338146 23951 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: aaa8cb51-92c8-445f-af35-96a50955663e) for task 1 of framework 201306272300-453759884-48144-23872-0000 to executor(180)@140.211.11.27:48144 I0627 23:00:57.338713 23946 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=56265) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.339041 23951 slave.cpp:1342] Status update manager successfully handled status update acknowledgement ce08e278-8d24-4172-9bb6-c13ced8a79b5 for task 0 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.339170 23941 exec.cpp:290] Executor received status update acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.339715 23951 status_update_manager.cpp:360] Received status update acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.340579 23951 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.340838 23951 slave.cpp:1342] Status update manager successfully handled status update acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of framework 201306272300-453759884-48144-23872-0000 E0627 23:00:57.341411 23951 slave.cpp:1366] Status update acknowledgement aaa8cb51-92c8-445f-af35-96a50955663e for task 1 of unknown executor I0627 23:00:57.365216 23937 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.366022 23937 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 840.536us I0627 23:00:57.366091 23943 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.367738 23943 master.cpp:1239] Sending 1 offers to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.369081 23957 sched.cpp:427] Stopping framework '201306272300-453759884-48144-23872-0000' I0627 23:00:57.369406 23953 master.cpp:385] Master terminating I0627 23:00:57.370388 23953 slave.cpp:484] Slave asked to shut down by [email protected]:48144 I0627 23:00:57.371018 23953 slave.cpp:1111] Asked to shut down framework 201306272300-453759884-48144-23872-0000 by [email protected]:48144 I0627 23:00:57.371784 23953 slave.cpp:1136] Shutting down framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.372477 23953 slave.cpp:2327] Shutting down executor 'default' of framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.370450 23872 master.cpp:207] Shutting down master I0627 23:00:57.373101 23953 slave.cpp:1883] [email protected]:48144 exited I0627 23:00:57.373152 23938 exec.cpp:323] Executor asked to shutdown I0627 23:00:57.373823 23872 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.373843 23952 hierarchical_allocator_process.hpp:412] Deactivated framework 201306272300-453759884-48144-23872-0000 W0627 23:00:57.374447 23953 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0627 23:00:57.375773 23872 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.376587 23952 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.377497 23953 slave.cpp:1111] Asked to shut down framework 201306272300-453759884-48144-23872-0000 by [email protected]:48144 W0627 23:00:57.378933 23953 slave.cpp:1132] Ignoring shutdown framework 201306272300-453759884-48144-23872-0000 because it is terminating I0627 23:00:57.379848 23953 slave.cpp:439] Slave terminating I0627 23:00:57.380318 23953 slave.cpp:1111] Asked to shut down framework 201306272300-453759884-48144-23872-0000 by @0.0.0.0:0 W0627 23:00:57.380803 23953 slave.cpp:1132] Ignoring shutdown framework 201306272300-453759884-48144-23872-0000 because it is terminating I0627 23:00:57.378604 23952 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=56265 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=56265) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.382223 23952 hierarchical_allocator_process.hpp:367] Removed framework 201306272300-453759884-48144-23872-0000 [ OK ] AllocatorTest/0.TaskFinished (69 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0627 23:00:57.384227 23955 master.cpp:228] Master started on 140.211.11.27:48144 I0627 23:00:57.384495 23955 master.cpp:243] Master ID: 201306272300-453759884-48144-23872 I0627 23:00:57.385160 23949 slave.cpp:112] Slave started on 490)@140.211.11.27:48144 I0627 23:00:57.385202 23942 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:48144 I0627 23:00:57.385308 23955 master.cpp:526] Elected as master! I0627 23:00:57.385730 23949 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=56265 I0627 23:00:57.386085 23942 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0627 23:00:57.386090 23956 sched.cpp:177] New master at [email protected]:48144 I0627 23:00:57.387487 23942 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0627 23:00:57.387588 23949 slave.cpp:528] New master detected at [email protected]:48144 I0627 23:00:57.387670 23942 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 184.621us I0627 23:00:57.387703 23940 master.cpp:569] Registering framework 201306272300-453759884-48144-23872-0000 at scheduler(445)@140.211.11.27:48144 I0627 23:00:57.387820 23949 slave.cpp:543] Postponing registration until recovery is complete I0627 23:00:57.387835 23957 status_update_manager.cpp:155] New master detected at [email protected]:48144 I0627 23:00:57.388195 23956 sched.cpp:222] Framework registered with 201306272300-453759884-48144-23872-0000 I0627 23:00:57.388206 23940 master.cpp:556] Framework 201306272300-453759884-48144-23872-0000 (scheduler(445)@140.211.11.27:48144) already registered, resending acknowledgement I0627 23:00:57.388288 23936 hierarchical_allocator_process.hpp:327] Added framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.388357 23949 slave.cpp:389] Finished recovery I0627 23:00:57.389610 23940 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0627 23:00:57.390117 23936 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0627 23:00:57.390511 23950 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(490)@140.211.11.27:48144 I0627 23:00:57.391216 23936 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 1.112221ms I0627 23:00:57.391616 23950 master.cpp:1851] Adding slave 201306272300-453759884-48144-23872-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=56265 I0627 23:00:57.392468 23953 slave.cpp:588] Registered with master [email protected]:48144; given slave ID 201306272300-453759884-48144-23872-0 I0627 23:00:57.392550 23950 master.cpp:880] Slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) already registered, resending acknowledgement I0627 23:00:57.392781 23934 hierarchical_allocator_process.hpp:449] Added slave 201306272300-453759884-48144-23872-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=56265 (and cpus=2; mem=1024; ports=[31000-32000]; disk=56265 available) W0627 23:00:57.393403 23950 slave.cpp:612] Already registered with master [email protected]:48144 I0627 23:00:57.396430 23953 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 43.064us I0627 23:00:57.406538 23954 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 38.029us I0627 23:00:57.416595 23945 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 36.73us I0627 23:00:57.426847 23938 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.492us I0627 23:00:57.427696 23938 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, hemera.apache.org } I0627 23:00:57.436807 23957 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.437743 23957 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 954.204us I0627 23:00:57.437881 23947 master.hpp:313] Adding offer 201306272300-453759884-48144-23872-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.439260 23947 master.cpp:1239] Sending 1 offers to framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.446821 23872 master.cpp:385] Master terminating I0627 23:00:57.446869 23956 sched.cpp:427] Stopping framework '201306272300-453759884-48144-23872-0000' I0627 23:00:57.447597 23872 master.cpp:207] Shutting down master I0627 23:00:57.447609 23937 slave.cpp:484] Slave asked to shut down by [email protected]:48144 I0627 23:00:57.449786 23937 slave.cpp:439] Slave terminating I0627 23:00:57.449427 23951 hierarchical_allocator_process.hpp:412] Deactivated framework 201306272300-453759884-48144-23872-0000 I0627 23:00:57.449147 23872 master.hpp:323] Removing offer 201306272300-453759884-48144-23872-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=56265 on slave 201306272300-453759884-48144-23872-0 I0627 23:00:57.451156 23951 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=56265 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=56265) on slave 201306272300-453759884-48144-23872-0 from framework 201306272300-453759884-48144-23872-0000 [ OK ] AllocatorTest/0.WhitelistSlave (70 ms) [----------] 9 tests from AllocatorTest/0 (569 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0627 23:00:57.452721 23943 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.455271 23958 process.cpp:878] Socket closed while receiving I0627 23:00:57.456212 23935 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.457068 23958 process.cpp:878] Socket closed while receiving I0627 23:00:57.458972 23945 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.460333 23958 process.cpp:878] Socket closed while receiving I0627 23:00:57.461539 23936 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.463479 23958 process.cpp:878] Socket closed while receiving I0627 23:00:57.464629 23956 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.466375 23958 process.cpp:878] Socket closed while receiving I0627 23:00:57.467465 23944 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0627 23:00:57.469248 23958 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (18 ms) [----------] 1 test from LoggingTest (18 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. (16950 ms total) [ PASSED ] 167 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ReaperTest.TerminatedChildProcess 1 FAILED TEST 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 I0627 23:01:00.328953 31082 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
