See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1285/>
------------------------------------------ [...truncated 50941 lines...] I0628 12:09:46.005398 23760 master.cpp:1472] Processing reply for offer 201306281209-1015726915-47508-23737-3 on slave 201306281209-1015726915-47508-23737-1 (janus.apache.org) for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.005810 23760 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1 I0628 12:09:46.005863 23763 hierarchical_allocator_process.hpp:526] Framework 201306281209-1015726915-47508-23737-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=21009 unused on slave 201306281209-1015726915-47508-23737-1 I0628 12:09:46.054713 23756 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.054841 23756 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1 to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.055295 23756 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 630.587us I0628 12:09:46.055348 23758 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1 I0628 12:09:46.056640 23758 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.057001 23758 master.cpp:1239] Sending 2 offers to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.057708 23762 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000' I0628 12:09:46.057718 23737 master.cpp:385] Master terminating I0628 12:09:46.058405 23737 master.cpp:207] Shutting down master I0628 12:09:46.058447 23757 slave.cpp:484] Slave asked to shut down by [email protected]:47508 I0628 12:09:46.058449 23756 slave.cpp:484] Slave asked to shut down by [email protected]:47508 I0628 12:09:46.059823 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by [email protected]:47508 I0628 12:09:46.060255 23756 slave.cpp:1136] Shutting down framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.060729 23756 slave.cpp:2327] Shutting down executor 'default' of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.061221 23756 slave.cpp:1883] [email protected]:47508 exited W0628 12:09:46.061667 23756 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 12:09:46.062144 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by [email protected]:47508 I0628 12:09:46.061226 23758 exec.cpp:323] Executor asked to shutdown I0628 12:09:46.058928 23737 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.058936 23760 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.064780 23760 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.065387 23760 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=21009 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.063691 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.066293 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1 W0628 12:09:46.062613 23756 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating I0628 12:09:46.067193 23756 slave.cpp:439] Slave terminating I0628 12:09:46.067914 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by @0.0.0.0:0 W0628 12:09:46.069113 23756 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating I0628 12:09:46.066401 23763 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=21009 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-1 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.059348 23757 slave.cpp:439] Slave terminating I0628 12:09:46.070935 23763 hierarchical_allocator_process.hpp:367] Removed framework 201306281209-1015726915-47508-23737-0000 [ OK ] AllocatorTest/0.SlaveAdded (174 ms) [ RUN ] AllocatorTest/0.TaskFinished I0628 12:09:46.072330 23761 master.cpp:228] Master started on 67.195.138.60:47508 I0628 12:09:46.072387 23761 master.cpp:243] Master ID: 201306281209-1015726915-47508-23737 I0628 12:09:46.072919 23763 slave.cpp:112] Slave started on 489)@67.195.138.60:47508 W0628 12:09:46.072959 23760 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 12:09:46.073075 23756 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:47508 I0628 12:09:46.073149 23761 master.cpp:526] Elected as master! I0628 12:09:46.073421 23757 sched.cpp:177] New master at [email protected]:47508 I0628 12:09:46.073912 23763 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=21009 I0628 12:09:46.075824 23757 master.cpp:569] Registering framework 201306281209-1015726915-47508-23737-0000 at scheduler(444)@67.195.138.60:47508 I0628 12:09:46.076977 23757 sched.cpp:222] Framework registered with 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.076983 23763 slave.cpp:528] New master detected at [email protected]:47508 I0628 12:09:46.077076 23758 hierarchical_allocator_process.hpp:327] Added framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.079370 23758 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 12:09:46.078194 23762 status_update_manager.cpp:155] New master detected at [email protected]:47508 I0628 12:09:46.078152 23763 slave.cpp:543] Postponing registration until recovery is complete I0628 12:09:46.081202 23763 slave.cpp:389] Finished recovery I0628 12:09:46.080281 23758 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 912.02us I0628 12:09:46.081814 23761 master.cpp:891] Attempting to register slave on janus.apache.org at slave(489)@67.195.138.60:47508 I0628 12:09:46.082732 23761 master.cpp:1851] Adding slave 201306281209-1015726915-47508-23737-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=21009 I0628 12:09:46.083245 23757 slave.cpp:588] Registered with master [email protected]:47508; given slave ID 201306281209-1015726915-47508-23737-0 I0628 12:09:46.083427 23756 hierarchical_allocator_process.hpp:449] Added slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=21009 (and cpus=3; mem=1024; ports=[31000-32000]; disk=21009 available) I0628 12:09:46.084450 23756 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.085021 23756 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281209-1015726915-47508-23737-0 in 584.475us I0628 12:09:46.085063 23757 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.085921 23757 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.086766 23761 master.cpp:1472] Processing reply for offer 201306281209-1015726915-47508-23737-0 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.087074 23761 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.087472 23761 master.cpp:1591] Launching task 0 of framework 201306281209-1015726915-47508-23737-0000 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) I0628 12:09:46.087991 23757 slave.cpp:738] Got assigned task 0 for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.088011 23761 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.089640 23761 master.cpp:1591] Launching task 1 of framework 201306281209-1015726915-47508-23737-0000 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) I0628 12:09:46.089274 23757 slave.cpp:836] Launching task 0 for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.090827 23761 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.090880 23760 hierarchical_allocator_process.hpp:526] Framework 201306281209-1015726915-47508-23737-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=21009 unused on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.092247 23760 hierarchical_allocator_process.hpp:569] Framework 201306281209-1015726915-47508-23737-0000 filtered slave 201306281209-1015726915-47508-23737-0 for 5secs I0628 12:09:46.092474 23757 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_Zwl2Xi/slaves/201306281209-1015726915-47508-23737-0/frameworks/201306281209-1015726915-47508-23737-0000/executors/default/runs/4f449ddb-02bd-4141-a97c-cf1ad4102752' I0628 12:09:46.093617 23757 slave.cpp:947] Queuing task '0' for executor default of framework '201306281209-1015726915-47508-23737-0000 I0628 12:09:46.093942 23757 slave.cpp:738] Got assigned task 1 for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.093782 23758 exec.cpp:170] Executor started at: executor(180)@67.195.138.60:47508 with pid 23737 I0628 12:09:46.094519 23757 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_Zwl2Xi/slaves/201306281209-1015726915-47508-23737-0/frameworks/201306281209-1015726915-47508-23737-0000/executors/default/runs/4f449ddb-02bd-4141-a97c-cf1ad4102752' I0628 12:09:46.095646 23757 slave.cpp:836] Launching task 1 for framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.096089 23757 slave.cpp:947] Queuing task '1' for executor default of framework '201306281209-1015726915-47508-23737-0000 I0628 12:09:46.097242 23757 slave.cpp:1394] Got registration for executor 'default' of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.097761 23757 slave.cpp:1509] Flushing queued task 1 for executor 'default' of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.097810 23761 exec.cpp:194] Executor registered on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.098230 23757 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.099326 23761 exec.cpp:258] Executor asked to run task '1' I0628 12:09:46.100321 23761 exec.cpp:258] Executor asked to run task '0' I0628 12:09:46.101646 23761 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.102099 23760 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508 I0628 12:09:46.102570 23760 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false I0628 12:09:46.103164 23760 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.103710 23760 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to [email protected]:47508 I0628 12:09:46.103076 23761 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.104202 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.104225 23762 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 1 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_RUNNING I0628 12:09:46.105116 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508 I0628 12:09:46.106330 23758 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508 I0628 12:09:46.105640 23761 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.107528 23756 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false I0628 12:09:46.107852 23761 exec.cpp:290] Executor received status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.107867 23758 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508 I0628 12:09:46.108381 23756 status_update_manager.cpp:360] Received status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.109941 23756 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to [email protected]:47508 I0628 12:09:46.109379 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.110441 23763 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 1 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_FINISHED I0628 12:09:46.110465 23756 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false I0628 12:09:46.112081 23756 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.112571 23756 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 to [email protected]:47508 I0628 12:09:46.110858 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508 I0628 12:09:46.114163 23758 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.111634 23763 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.114179 23757 exec.cpp:290] Executor received status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.114670 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.116683 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508 I0628 12:09:46.117229 23758 exec.cpp:290] Executor received status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.115238 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.114681 23760 status_update_manager.cpp:360] Received status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.115157 23763 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 0 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_RUNNING I0628 12:09:46.118453 23760 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.119467 23760 status_update_manager.cpp:360] Received status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.119474 23762 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000 E0628 12:09:46.120436 23762 slave.cpp:1366] Status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of unknown executor I0628 12:09:46.121000 23762 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.124941 23761 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.125120 23761 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 215.216us I0628 12:09:46.125169 23760 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.125852 23760 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.126494 23756 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000' I0628 12:09:46.126516 23737 master.cpp:385] Master terminating I0628 12:09:46.128407 23760 slave.cpp:484] Slave asked to shut down by [email protected]:47508 I0628 12:09:46.128885 23760 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by [email protected]:47508 I0628 12:09:46.129354 23760 slave.cpp:1136] Shutting down framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.129835 23760 slave.cpp:2327] Shutting down executor 'default' of framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.130352 23763 exec.cpp:323] Executor asked to shutdown I0628 12:09:46.130352 23760 slave.cpp:1883] [email protected]:47508 exited W0628 12:09:46.131372 23760 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 12:09:46.128414 23737 master.cpp:207] Shutting down master I0628 12:09:46.133796 23759 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by [email protected]:47508 W0628 12:09:46.134182 23759 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating I0628 12:09:46.133824 23758 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.133805 23737 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.136201 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.136801 23737 slave.cpp:439] Slave terminating I0628 12:09:46.136262 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.138067 23737 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by @0.0.0.0:0 W0628 12:09:46.139924 23737 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating I0628 12:09:46.139439 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=21009 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 [ OK ] AllocatorTest/0.TaskFinished (69 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0628 12:09:46.141948 23757 master.cpp:228] Master started on 67.195.138.60:47508 I0628 12:09:46.142029 23757 master.cpp:243] Master ID: 201306281209-1015726915-47508-23737 I0628 12:09:46.142592 23757 master.cpp:526] Elected as master! I0628 12:09:46.142802 23758 slave.cpp:112] Slave started on 490)@67.195.138.60:47508 I0628 12:09:46.143334 23760 sched.cpp:177] New master at [email protected]:47508 I0628 12:09:46.143363 23758 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=21009 I0628 12:09:46.142897 23759 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:47508 I0628 12:09:46.145805 23759 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave } I0628 12:09:46.146209 23759 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 12:09:46.146713 23759 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 505.849us I0628 12:09:46.144863 23758 slave.cpp:528] New master detected at [email protected]:47508 I0628 12:09:46.143920 23761 master.cpp:569] Registering framework 201306281209-1015726915-47508-23737-0000 at scheduler(445)@67.195.138.60:47508 I0628 12:09:46.147727 23758 slave.cpp:543] Postponing registration until recovery is complete I0628 12:09:46.148736 23758 slave.cpp:389] Finished recovery I0628 12:09:46.148263 23756 sched.cpp:222] Framework registered with 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.148262 23761 master.cpp:556] Framework 201306281209-1015726915-47508-23737-0000 (scheduler(445)@67.195.138.60:47508) already registered, resending acknowledgement I0628 12:09:46.150290 23759 sched.cpp:217] Ignoring framework registered message because the driver is already connected! I0628 12:09:46.147735 23760 status_update_manager.cpp:155] New master detected at [email protected]:47508 I0628 12:09:46.150303 23761 master.cpp:891] Attempting to register slave on janus.apache.org at slave(490)@67.195.138.60:47508 I0628 12:09:46.151723 23761 master.cpp:1851] Adding slave 201306281209-1015726915-47508-23737-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21009 I0628 12:09:46.152252 23762 slave.cpp:588] Registered with master [email protected]:47508; given slave ID 201306281209-1015726915-47508-23737-0 I0628 12:09:46.152298 23761 master.cpp:880] Slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) already registered, resending acknowledgement W0628 12:09:46.153448 23761 slave.cpp:612] Already registered with master [email protected]:47508 I0628 12:09:46.148325 23763 hierarchical_allocator_process.hpp:327] Added framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.155356 23763 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 12:09:46.155853 23763 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 497.419us I0628 12:09:46.156395 23763 hierarchical_allocator_process.hpp:449] Added slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21009 (and cpus=2; mem=1024; ports=[31000-32000]; disk=21009 available) I0628 12:09:46.163324 23762 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 14.585us I0628 12:09:46.173369 23759 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 18.319us I0628 12:09:46.183423 23760 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.745us I0628 12:09:46.193660 23761 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.788us I0628 12:09:46.193790 23756 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, janus.apache.org } I0628 12:09:46.203547 23758 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.203691 23758 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 158.714us I0628 12:09:46.203742 23763 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.204629 23763 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.213623 23763 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000' I0628 12:09:46.213645 23737 master.cpp:385] Master terminating I0628 12:09:46.214021 23737 master.cpp:207] Shutting down master I0628 12:09:46.214491 23756 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.215039 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=21009 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000 I0628 12:09:46.214040 23760 slave.cpp:484] Slave asked to shut down by [email protected]:47508 I0628 12:09:46.216691 23760 slave.cpp:439] Slave terminating I0628 12:09:46.214520 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 I0628 12:09:46.217767 23759 hierarchical_allocator_process.hpp:367] Removed framework 201306281209-1015726915-47508-23737-0000 [ OK ] AllocatorTest/0.WhitelistSlave (77 ms) [----------] 9 tests from AllocatorTest/0 (576 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0628 12:09:46.218479 23756 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.218997 23764 process.cpp:878] Socket closed while receiving I0628 12:09:46.219516 23759 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.219959 23764 process.cpp:878] Socket closed while receiving I0628 12:09:46.220372 23763 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.221164 23764 process.cpp:878] Socket closed while receiving I0628 12:09:46.222383 23762 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.222774 23764 process.cpp:878] Socket closed while receiving I0628 12:09:46.223268 23759 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.223829 23764 process.cpp:878] Socket closed while receiving I0628 12:09:46.224349 23763 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0628 12:09:46.224750 23764 process.cpp:878] Socket closed while receiving [ OK ] LoggingTest.Toggle (7 ms) [----------] 1 test from LoggingTest (7 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. (77372 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 `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 I0628 12:09:48.682320 8188 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
