See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/335/changes>
Changes: [vinod] Fixed scheduler driver to call disconnected() when master fails over. [vinod] Fixed master to send a FrameworkReregistered message when the ------------------------------------------ [...truncated 12284 lines...] I0524 07:02:20.519335 29117 master.cpp:242] Master ID: 201305240702-982172483-40828-28992 I0524 07:02:20.520148 29123 slave.cpp:215] Slave started on 93)@67.195.138.58:40828 I0524 07:02:20.520617 29123 slave.cpp:216] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=22523 I0524 07:02:20.520148 29117 master.cpp:510] Elected as master! I0524 07:02:20.520287 29118 sched.cpp:182] New master at [email protected]:40828 I0524 07:02:20.520289 29122 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:40828 W0524 07:02:20.520179 29119 master.cpp:82] No whitelist given. Advertising offers for all slaves I0524 07:02:20.521877 29123 slave.cpp:535] New master detected at [email protected]:40828 I0524 07:02:20.524168 29118 master.cpp:553] Registering framework 201305240702-982172483-40828-28992-0000 at scheduler(84)@67.195.138.58:40828 I0524 07:02:20.527164 29123 slave.cpp:550] Postponing registration until recovery is complete I0524 07:02:20.527191 29124 status_update_manager.cpp:154] New master detected at [email protected]:40828 I0524 07:02:20.528046 29121 sched.cpp:227] Framework registered with 201305240702-982172483-40828-28992-0000 I0524 07:02:20.528095 29117 hierarchical_allocator_process.hpp:268] Added framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.566390 29123 slave.cpp:396] Finished recovery I0524 07:02:20.569834 29117 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:20.572469 29117 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 2.637068000000000ms I0524 07:02:20.571286 29123 master.cpp:875] Attempting to register slave on quirinus.apache.org at slave(93)@67.195.138.58:40828 I0524 07:02:20.575562 29123 master.cpp:1837] Adding slave 201305240702-982172483-40828-28992-0 at quirinus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=22523 I0524 07:02:20.575929 29122 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:20.576520 29123 slave.cpp:595] Registered with master [email protected]:40828; given slave ID 201305240702-982172483-40828-28992-0 I0524 07:02:20.577709 29122 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.787210000000000ms I0524 07:02:20.579668 29122 hierarchical_allocator_process.hpp:395] Added slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=22523 (and cpus=3; mem=1024; ports=[31000-32000]; disk=22523 available) I0524 07:02:20.580818 29122 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.581982 29122 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.582839 29122 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305240702-982172483-40828-28992-0 in 2.030394000000000ms I0524 07:02:20.582885 29123 master.hpp:305] Adding offer 201305240702-982172483-40828-28992-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.585130 29123 master.cpp:1265] Sending 1 offers to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.585963 29118 sched.cpp:292] Received 1 offers I0524 07:02:20.587424 29118 master.cpp:1498] Processing reply for offer 201305240702-982172483-40828-28992-0 on slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.588502 29118 master.hpp:283] Adding task 0 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.589581 29118 master.cpp:1617] Launching task 0 of framework 201305240702-982172483-40828-28992-0000 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) I0524 07:02:20.590800 29118 master.hpp:283] Adding task 1 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.590829 29123 slave.cpp:724] Got assigned task 0 for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.591541 29118 master.cpp:1617] Launching task 1 of framework 201305240702-982172483-40828-28992-0000 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) I0524 07:02:20.592803 29123 slave.cpp:822] Launching task 0 for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.593884 29118 master.hpp:315] Removing offer 201305240702-982172483-40828-28992-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.595886 29123 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_Wp0OND/slaves/201305240702-982172483-40828-28992-0/frameworks/201305240702-982172483-40828-28992-0000/executors/default/runs/5fec4939-62f4-4ae7-844f-40888e86e8ca' I0524 07:02:20.593951 29119 hierarchical_allocator_process.hpp:471] Framework 201305240702-982172483-40828-28992-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=22523 unused on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.597115 29123 slave.cpp:933] Queuing task '0' for executor default of framework '201305240702-982172483-40828-28992-0000 I0524 07:02:20.597307 29121 exec.cpp:170] Executor started at: executor(32)@67.195.138.58:40828 with pid 28992 I0524 07:02:20.599345 29123 slave.cpp:724] Got assigned task 1 for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.601279 29123 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_Wp0OND/slaves/201305240702-982172483-40828-28992-0/frameworks/201305240702-982172483-40828-28992-0000/executors/default/runs/5fec4939-62f4-4ae7-844f-40888e86e8ca' I0524 07:02:20.602423 29123 slave.cpp:1385] Got registration for executor 'default' of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.603556 29123 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.603597 29120 exec.cpp:194] Executor registered on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.611696 29123 slave.cpp:822] Launching task 1 for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.612931 29120 exec.cpp:258] Executor asked to run task '0' I0524 07:02:20.622802 29123 slave.cpp:958] Sending task '1' to executor 'default' of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.624881 29120 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.626652 29120 exec.cpp:258] Executor asked to run task '1' I0524 07:02:20.626673 29121 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.628629 29120 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.628898 29119 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 with checkpoint=false I0524 07:02:20.630446 29121 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.630571 29120 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.631844 29119 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.633097 29121 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.635125 29119 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 to [email protected]:40828 I0524 07:02:20.635931 29121 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 5.498167000000000ms I0524 07:02:20.636041 29122 master.hpp:305] Adding offer 201305240702-982172483-40828-28992-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.664336 29119 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 with checkpoint=false I0524 07:02:20.664350 29124 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.678092 29122 master.cpp:1265] Sending 1 offers to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.678992 29119 status_update_manager.cpp:449] Creating StatusUpdate stream for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.682932 29119 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 to [email protected]:40828 I0524 07:02:20.682106 29122 master.cpp:1048] Status update from slave(93)@67.195.138.58:40828: task 0 of framework 201305240702-982172483-40828-28992-0000 is now in state TASK_RUNNING I0524 07:02:20.682142 29117 sched.cpp:292] Received 1 offers I0524 07:02:20.680286 29124 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 to executor(32)@67.195.138.58:40828 I0524 07:02:20.686558 29124 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.687409 29124 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 to executor(32)@67.195.138.58:40828 I0524 07:02:20.686919 29121 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.689049 29121 exec.cpp:290] Executor received status update acknowledgement d463a79f-e4d4-40ee-bf76-d852a1098a71 for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.689885 29121 exec.cpp:290] Executor received status update acknowledgement 68c8b078-ac47-4c96-b065-dcfac85221bc for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.684777 29122 master.cpp:1048] Status update from slave(93)@67.195.138.58:40828: task 1 of framework 201305240702-982172483-40828-28992-0000 is now in state TASK_RUNNING I0524 07:02:20.689057 29119 slave.cpp:1729] Handling status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.685720 29117 sched.cpp:337] Received status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 from slave(93)@67.195.138.58:40828 I0524 07:02:20.692441 29118 status_update_manager.cpp:289] Received status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 with checkpoint=false I0524 07:02:20.693106 29119 master.cpp:1498] Processing reply for offer 201305240702-982172483-40828-28992-1 on slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) for framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.694705 29119 master.hpp:315] Removing offer 201305240702-982172483-40828-28992-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.693868 29124 slave.cpp:1786] Status update manager successfully handled status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.696213 29124 slave.cpp:1792] Sending acknowledgement for status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 to executor(32)@67.195.138.58:40828 I0524 07:02:20.694732 29118 hierarchical_allocator_process.hpp:471] Framework 201305240702-982172483-40828-28992-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=22523 unused on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.693112 29117 sched.cpp:337] Received status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 from slave(93)@67.195.138.58:40828 I0524 07:02:20.698701 29117 sched.cpp:370] Sending ACK for status update TASK_RUNNING (UUID: d463a79f-e4d4-40ee-bf76-d852a1098a71) for task 0 of framework 201305240702-982172483-40828-28992-0000 to slave(93)@67.195.138.58:40828 I0524 07:02:20.699477 29117 sched.cpp:370] Sending ACK for status update TASK_RUNNING (UUID: 68c8b078-ac47-4c96-b065-dcfac85221bc) for task 1 of framework 201305240702-982172483-40828-28992-0000 to slave(93)@67.195.138.58:40828 I0524 07:02:20.697023 29122 exec.cpp:290] Executor received status update acknowledgement 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.699530 29124 status_update_manager.cpp:359] Received status update acknowledgement d463a79f-e4d4-40ee-bf76-d852a1098a71 for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.701902 29124 status_update_manager.cpp:335] Forwarding status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 to [email protected]:40828 I0524 07:02:20.702720 29124 status_update_manager.cpp:359] Received status update acknowledgement 68c8b078-ac47-4c96-b065-dcfac85221bc for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.702726 29117 master.cpp:1048] Status update from slave(93)@67.195.138.58:40828: task 0 of framework 201305240702-982172483-40828-28992-0000 is now in state TASK_FINISHED I0524 07:02:20.704299 29117 master.hpp:295] Removing task 0 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.704304 29124 sched.cpp:337] Received status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 from slave(93)@67.195.138.58:40828 I0524 07:02:20.705870 29124 sched.cpp:370] Sending ACK for status update TASK_FINISHED (UUID: 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a) for task 0 of framework 201305240702-982172483-40828-28992-0000 to slave(93)@67.195.138.58:40828 I0524 07:02:20.705183 29123 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=22523) on slave 201305240702-982172483-40828-28992-0 from framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.702752 29121 slave.cpp:1335] Status update manager successfully handled status update acknowledgement d463a79f-e4d4-40ee-bf76-d852a1098a71 for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.708716 29121 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 68c8b078-ac47-4c96-b065-dcfac85221bc for task 1 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.709923 29121 status_update_manager.cpp:359] Received status update acknowledgement 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.711019 29121 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.715764 29120 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.750838 29121 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 0e7ebc68-76d5-4ec6-8aa0-7efc0aa3cf1a for task 0 of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.806926 29120 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.834661 29120 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 118.911664000000016ms I0524 07:02:20.834720 29122 master.hpp:305] Adding offer 201305240702-982172483-40828-28992-2 with resources cpus=2; mem=768; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.859603 29122 master.cpp:1265] Sending 1 offers to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.869213 29119 sched.cpp:292] Received 1 offers I0524 07:02:20.876078 29124 sched.cpp:432] Stopping framework '201305240702-982172483-40828-28992-0000' I0524 07:02:20.883149 29124 master.cpp:681] Asked to unregister framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.892823 29120 slave.cpp:1104] Asked to shut down framework 201305240702-982172483-40828-28992-0000 by [email protected]:40828 I0524 07:02:20.892822 29124 master.hpp:295] Removing task 1 with resources cpus=1; mem=256 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.892853 29119 hierarchical_allocator_process.hpp:359] Deactivated framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.903715 29120 slave.cpp:1129] Shutting down framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.914713 29124 master.hpp:315] Removing offer 201305240702-982172483-40828-28992-2 with resources cpus=2; mem=768; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:20.924782 29119 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:20.932996 29120 slave.cpp:2302] Shutting down executor 'default' of framework 201305240702-982172483-40828-28992-0000 I0524 07:02:20.944026 28992 master.cpp:384] Master terminating I0524 07:02:20.952525 29119 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 27.765063000000001ms I0524 07:02:20.963197 29118 exec.cpp:323] Executor asked to shutdown I0524 07:02:20.973521 28992 master.cpp:206] Shutting down master I0524 07:02:20.973538 29120 slave.cpp:491] Slave asked to shut down by [email protected]:40828 I0524 07:02:20.982485 29119 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201305240702-982172483-40828-28992-0 from framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.013582 29120 slave.cpp:1104] Asked to shut down framework 201305240702-982172483-40828-28992-0000 by [email protected]:40828 W0524 07:02:21.036226 29120 slave.cpp:1125] Ignoring shutdown framework 201305240702-982172483-40828-28992-0000 because it is terminating I0524 07:02:21.044806 29120 slave.cpp:446] Slave terminating I0524 07:02:21.051439 29120 slave.cpp:1104] Asked to shut down framework 201305240702-982172483-40828-28992-0000 by @0.0.0.0:0 W0524 07:02:21.061787 29120 slave.cpp:1125] Ignoring shutdown framework 201305240702-982172483-40828-28992-0000 because it is terminating I0524 07:02:21.040002 29123 monitor.cpp:161] Publishing resource usage for executor 'default' of framework '201305240702-982172483-40828-28992-0000' I0524 07:02:21.023941 29119 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=22523 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=22523) on slave 201305240702-982172483-40828-28992-0 from framework 201305240702-982172483-40828-28992-0000 [ OK ] AllocatorTest/0.TaskFinished (560 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0524 07:02:21.078712 29123 master.cpp:227] Master started on 67.195.138.58:40828 I0524 07:02:21.079056 29120 slave.cpp:215] Slave started on 94)@67.195.138.58:40828 I0524 07:02:21.087342 29123 master.cpp:242] Master ID: 201305240702-982172483-40828-28992 I0524 07:02:21.103485 29120 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=22523 I0524 07:02:21.116571 29123 master.cpp:510] Elected as master! I0524 07:02:21.116569 29117 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:40828 I0524 07:02:21.116616 29121 sched.cpp:182] New master at [email protected]:40828 I0524 07:02:21.117789 29120 slave.cpp:535] New master detected at [email protected]:40828 I0524 07:02:21.132103 29117 hierarchical_allocator_process.hpp:437] Updated slave white list: I0524 07:02:21.136067 29117 hierarchical_allocator_process.hpp:439] dummy-slave I0524 07:02:21.134199 29120 slave.cpp:550] Postponing registration until recovery is complete I0524 07:02:21.134229 29122 status_update_manager.cpp:154] New master detected at [email protected]:40828 I0524 07:02:21.133420 29121 master.cpp:553] Registering framework 201305240702-982172483-40828-28992-0000 at scheduler(85)@67.195.138.58:40828 I0524 07:02:21.145269 29117 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0524 07:02:21.146231 29120 slave.cpp:396] Finished recovery I0524 07:02:21.148843 29121 master.cpp:540] Framework 201305240702-982172483-40828-28992-0000 (scheduler(85)@67.195.138.58:40828) already registered, resending acknowledgement I0524 07:02:21.148902 29124 sched.cpp:227] Framework registered with 201305240702-982172483-40828-28992-0000 I0524 07:02:21.149991 29117 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 4.724089000000000ms I0524 07:02:21.152520 29121 master.cpp:875] Attempting to register slave on quirinus.apache.org at slave(94)@67.195.138.58:40828 I0524 07:02:21.153403 29124 sched.cpp:222] Ignoring framework registered message because the driver is already connected! I0524 07:02:21.154619 29117 hierarchical_allocator_process.hpp:268] Added framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.156203 29121 master.cpp:1837] Adding slave 201305240702-982172483-40828-28992-0 at quirinus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=22523 I0524 07:02:21.158792 29117 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.159986 29119 slave.cpp:595] Registered with master [email protected]:40828; given slave ID 201305240702-982172483-40828-28992-0 I0524 07:02:21.159996 29121 master.cpp:864] Slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) already registered, resending acknowledgement I0524 07:02:21.161031 29117 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 2.238882000000000ms I0524 07:02:21.164124 29117 hierarchical_allocator_process.hpp:395] Added slave 201305240702-982172483-40828-28992-0 (quirinus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=22523 (and cpus=2; mem=1024; ports=[31000-32000]; disk=22523 available) I0524 07:02:21.165258 29117 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.166025 29117 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305240702-982172483-40828-28992-0 in 773.259999999999991us I0524 07:02:21.177268 29121 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.177305 29121 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 55.021000000000001us I0524 07:02:21.187314 29122 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.194411 29122 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 7.110796000000001ms I0524 07:02:21.207429 29118 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.207469 29118 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 79.461000000000013us I0524 07:02:21.217458 29119 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0524 07:02:21.217490 29119 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 56.230000000000004us I0524 07:02:21.218719 29119 hierarchical_allocator_process.hpp:437] Updated slave white list: I0524 07:02:21.219554 29119 hierarchical_allocator_process.hpp:439] quirinus.apache.org I0524 07:02:21.220437 29119 hierarchical_allocator_process.hpp:439] dummy-slave I0524 07:02:21.227476 29123 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:21.227536 29123 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.241631 29123 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 14.181195000000001ms I0524 07:02:21.241682 29124 master.hpp:305] Adding offer 201305240702-982172483-40828-28992-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 I0524 07:02:21.252470 29124 master.cpp:1265] Sending 1 offers to framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.253738 29124 sched.cpp:292] Received 1 offers I0524 07:02:21.267895 29120 sched.cpp:432] Stopping framework '201305240702-982172483-40828-28992-0000' I0524 07:02:21.267951 29120 master.cpp:681] Asked to unregister framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.270900 29121 slave.cpp:1104] Asked to shut down framework 201305240702-982172483-40828-28992-0000 by [email protected]:40828 I0524 07:02:21.270925 29122 hierarchical_allocator_process.hpp:359] Deactivated framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.270923 29120 master.hpp:315] Removing offer 201305240702-982172483-40828-28992-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=22523 on slave 201305240702-982172483-40828-28992-0 W0524 07:02:21.272109 29121 slave.cpp:1119] Cannot shut down unknown framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.273453 29122 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=22523 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=22523) on slave 201305240702-982172483-40828-28992-0 from framework 201305240702-982172483-40828-28992-0000 I0524 07:02:21.281265 29118 master.cpp:384] Master terminating I0524 07:02:21.296486 29123 slave.cpp:491] Slave asked to shut down by [email protected]:40828 I0524 07:02:21.296537 28992 master.cpp:206] Shutting down master I0524 07:02:21.298269 29123 slave.cpp:446] Slave terminating [ OK ] AllocatorTest/0.WhitelistSlave (223 ms) [----------] 9 tests from AllocatorTest/0 (5623 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0524 07:02:21.301533 29120 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.302075 29125 process.cpp:870] Socket closed while receiving I0524 07:02:21.303207 29118 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.312127 29125 process.cpp:870] Socket closed while receiving I0524 07:02:21.313285 29118 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.314726 29125 process.cpp:870] Socket closed while receiving I0524 07:02:21.315887 29117 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.316521 29125 process.cpp:870] Socket closed while receiving I0524 07:02:21.318384 29119 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.320008 29125 process.cpp:870] Socket closed while receiving I0524 07:02:21.321022 29121 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0524 07:02:21.322257 29125 process.cpp:870] Socket closed while receiving [ OK ] LoggingTest.Toggle (22 ms) [----------] 1 test from LoggingTest (22 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 (1 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (24 ms) [----------] 3 tests from FsTest (25 ms total) [----------] Global test environment tear-down [==========] 173 tests from 36 test cases ran. (172452 ms total) [ PASSED ] 170 tests. [ FAILED ] 3 tests, listed below: [ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill [ FAILED ] CoordinatorTest.TruncateNotLearnedFill [ FAILED ] CoordinatorTest.TruncateLearnedFill 3 FAILED TESTS FAIL: mesos-tests ================== 1 of 1 test failed ================== make[3]: *** [check-TESTS] 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 Build step 'Execute shell' marked build as failure
