See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/326/>
------------------------------------------ [...truncated 11816 lines...] I0518 23:02:33.277490 1810 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.144828000000000ms I0518 23:02:33.274847 1807 slave.cpp:595] Registered with master [email protected]:47052; given slave ID 201305182302-1015726915-47052-1696-0 I0518 23:02:33.277557 1809 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000] on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.278892 1809 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.279631 1809 sched.cpp:282] Received 1 offers I0518 23:02:33.280563 1809 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000' I0518 23:02:33.280791 1808 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.281138 1807 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by [email protected]:47052 I0518 23:02:33.281175 1809 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.282155 1809 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000] (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=0) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000 W0518 23:02:33.281543 1807 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.281169 1808 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000] on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.284504 1812 slave.cpp:446] Slave terminating I0518 23:02:33.284764 1812 master.cpp:444] Slave 201305182302-1015726915-47052-1696-0(janus.apache.org) disconnected I0518 23:02:33.285192 1812 master.cpp:449] Removing disconnected slave 201305182302-1015726915-47052-1696-0(janus.apache.org) because it is not checkpointing! I0518 23:02:33.285786 1696 master.cpp:384] Master terminating I0518 23:02:33.286151 1696 master.cpp:206] Shutting down master [ OK ] AllocatorTest/0.MockAllocator (23 ms) [ RUN ] AllocatorTest/0.ResourcesUnused I0518 23:02:33.287569 1805 master.cpp:227] Master started on 67.195.138.60:47052 I0518 23:02:33.287643 1805 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696 I0518 23:02:33.287999 1807 slave.cpp:215] Slave started on 85)@67.195.138.60:47052 I0518 23:02:33.288439 1809 sched.cpp:182] New master at [email protected]:47052 W0518 23:02:33.288928 1808 master.cpp:82] No whitelist given. Advertising offers for all slaves I0518 23:02:33.289055 1811 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:47052 I0518 23:02:33.289147 1805 master.cpp:510] Elected as master! I0518 23:02:33.289208 1807 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.291303 1805 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(73)@67.195.138.60:47052 I0518 23:02:33.292261 1806 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.292292 1805 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.293815 1805 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0518 23:02:33.294317 1805 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 503.560000000000059us I0518 23:02:33.292397 1807 slave.cpp:535] New master detected at [email protected]:47052 I0518 23:02:33.295305 1807 slave.cpp:550] Postponing registration until recovery is complete I0518 23:02:33.295331 1812 status_update_manager.cpp:154] New master detected at [email protected]:47052 I0518 23:02:33.295800 1807 slave.cpp:396] Finished recovery I0518 23:02:33.297152 1810 master.cpp:875] Attempting to register slave on janus.apache.org at slave(85)@67.195.138.60:47052 I0518 23:02:33.297508 1810 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.298028 1808 slave.cpp:595] Registered with master [email protected]:47052; given slave ID 201305182302-1015726915-47052-1696-0 I0518 23:02:33.298202 1806 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20059 available) I0518 23:02:33.299103 1806 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.299592 1806 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.301419 1806 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 2.331852000000000ms I0518 23:02:33.301458 1808 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.302362 1808 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.302944 1805 sched.cpp:282] Received 1 offers I0518 23:02:33.303583 1810 master.cpp:1498] Processing reply for offer 201305182302-1015726915-47052-1696-0 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.303939 1810 master.hpp:283] Adding task 0 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.304581 1810 master.cpp:1617] Launching task 0 of framework 201305182302-1015726915-47052-1696-0000 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) I0518 23:02:33.305135 1811 slave.cpp:724] Got assigned task 0 for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.305193 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.305263 1809 hierarchical_allocator_process.hpp:471] Framework 201305182302-1015726915-47052-1696-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=20059 unused on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.306597 1809 hierarchical_allocator_process.hpp:497] Framework 201305182302-1015726915-47052-1696-0000 filtered slave 201305182302-1015726915-47052-1696-0 for 5.000000000000000secs I0518 23:02:33.305935 1811 slave.cpp:822] Launching task 0 for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.308712 1811 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_ResourcesUnused_3AzbRz/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/67796c49-f821-4fe3-a72d-6afae3c58db3' I0518 23:02:33.308930 1811 slave.cpp:933] Queuing task '0' for executor default of framework '201305182302-1015726915-47052-1696-0000 I0518 23:02:33.309087 1812 exec.cpp:170] Executor started at: executor(26)@67.195.138.60:47052 with pid 1696 I0518 23:02:33.309295 1811 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_ResourcesUnused_3AzbRz/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/67796c49-f821-4fe3-a72d-6afae3c58db3' I0518 23:02:33.310297 1811 slave.cpp:1385] Got registration for executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.310827 1811 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.310869 1806 exec.cpp:194] Executor registered on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.311848 1806 exec.cpp:258] Executor asked to run task '0' I0518 23:02:33.312662 1807 sched.cpp:182] New master at [email protected]:47052 I0518 23:02:33.313153 1807 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0001 at scheduler(74)@67.195.138.60:47052 I0518 23:02:33.313560 1807 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.313621 1812 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.314517 1812 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.315040 1812 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.316141 1812 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.640288000000000ms I0518 23:02:33.316196 1810 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.317677 1810 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.318152 1808 sched.cpp:282] Received 1 offers I0518 23:02:33.319005 1811 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000' I0518 23:02:33.319025 1812 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0001' I0518 23:02:33.319109 1809 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.320030 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by [email protected]:47052 I0518 23:02:33.320037 1809 master.hpp:295] Removing task 0 with resources cpus=1; mem=512 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.321352 1809 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.321885 1809 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.320098 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.323173 1810 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.322484 1696 master.cpp:384] Master terminating I0518 23:02:33.320659 1811 slave.cpp:1129] Shutting down framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.323767 1810 hierarchical_allocator_process.hpp:312] Removed framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.324165 1696 master.cpp:206] Shutting down master I0518 23:02:33.324638 1811 slave.cpp:2302] Shutting down executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.325836 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.326869 1811 slave.cpp:446] Slave terminating I0518 23:02:33.326872 1812 exec.cpp:323] Executor asked to shutdown I0518 23:02:33.327414 1810 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.327816 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by @0.0.0.0:0 I0518 23:02:33.328835 1810 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0 W0518 23:02:33.329525 1811 slave.cpp:1125] Ignoring shutdown framework 201305182302-1015726915-47052-1696-0000 because it is terminating [ OK ] AllocatorTest/0.ResourcesUnused (44 ms) [ RUN ] AllocatorTest/0.OutOfOrderDispatch I0518 23:02:33.331895 1809 master.cpp:227] Master started on 67.195.138.60:47052 I0518 23:02:33.331938 1809 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696 I0518 23:02:33.332753 1809 master.cpp:510] Elected as master! I0518 23:02:33.333019 1807 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:47052 I0518 23:02:33.332870 1812 sched.cpp:182] New master at [email protected]:47052 W0518 23:02:33.332896 1810 master.cpp:82] No whitelist given. Advertising offers for all slaves I0518 23:02:33.332325 1805 slave.cpp:215] Slave started on 86)@67.195.138.60:47052 I0518 23:02:33.336156 1805 slave.cpp:216] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.334857 1812 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(75)@67.195.138.60:47052 I0518 23:02:33.337095 1805 slave.cpp:535] New master detected at [email protected]:47052 I0518 23:02:33.337218 1808 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.337275 1811 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.339375 1811 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0518 23:02:33.339931 1811 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 557.950000000000045us I0518 23:02:33.337561 1805 slave.cpp:550] Postponing registration until recovery is complete I0518 23:02:33.340877 1805 slave.cpp:396] Finished recovery I0518 23:02:33.337579 1812 status_update_manager.cpp:154] New master detected at [email protected]:47052 I0518 23:02:33.341444 1809 master.cpp:875] Attempting to register slave on janus.apache.org at slave(86)@67.195.138.60:47052 I0518 23:02:33.342326 1809 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.342865 1807 slave.cpp:595] Registered with master [email protected]:47052; given slave ID 201305182302-1015726915-47052-1696-0 I0518 23:02:33.343024 1812 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20059 available) I0518 23:02:33.343915 1812 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.344395 1812 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.344979 1812 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.072582000000000ms I0518 23:02:33.345024 1807 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.345891 1807 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.346639 1811 sched.cpp:282] Received 1 offers I0518 23:02:33.347441 1807 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000' I0518 23:02:33.347597 1810 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.348155 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.348220 1805 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.348220 1806 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by [email protected]:47052 I0518 23:02:33.349020 1808 sched.cpp:182] New master at [email protected]:47052 I0518 23:02:33.349186 1805 hierarchical_allocator_process.hpp:312] Removed framework 201305182302-1015726915-47052-1696-0000 W0518 23:02:33.349627 1806 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.350903 1807 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0001 at scheduler(76)@67.195.138.60:47052 I0518 23:02:33.352332 1807 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.351320 1805 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.353807 1805 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.354244 1805 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.355623 1805 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.356116 1805 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.881034000000000ms I0518 23:02:33.356154 1812 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.357028 1812 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.357537 1812 sched.cpp:282] Received 1 offers I0518 23:02:33.358320 1808 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0001' I0518 23:02:33.358541 1808 master.cpp:681] Asked to unregister framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.359060 1808 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.359102 1810 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0001 by [email protected]:47052 W0518 23:02:33.360029 1810 slave.cpp:1119] Cannot shut down unknown framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.359642 1696 master.cpp:384] Master terminating I0518 23:02:33.359143 1811 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.361559 1811 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0001 I0518 23:02:33.361047 1696 master.cpp:206] Shutting down master I0518 23:02:33.361029 1807 slave.cpp:491] Slave asked to shut down by [email protected]:47052 I0518 23:02:33.362560 1809 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.363229 1807 slave.cpp:446] Slave terminating [ OK ] AllocatorTest/0.OutOfOrderDispatch (43 ms) [ RUN ] AllocatorTest/0.SchedulerFailover I0518 23:02:33.375377 1805 master.cpp:227] Master started on 67.195.138.60:47052 I0518 23:02:33.375463 1805 master.cpp:242] Master ID: 201305182302-1015726915-47052-1696 I0518 23:02:33.376016 1805 master.cpp:510] Elected as master! W0518 23:02:33.376116 1810 master.cpp:82] No whitelist given. Advertising offers for all slaves I0518 23:02:33.376147 1807 slave.cpp:215] Slave started on 87)@67.195.138.60:47052 I0518 23:02:33.377189 1807 slave.cpp:216] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.376453 1809 sched.cpp:182] New master at [email protected]:47052 I0518 23:02:33.378281 1809 master.cpp:553] Registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(77)@67.195.138.60:47052 I0518 23:02:33.378710 1809 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.378098 1807 slave.cpp:535] New master detected at [email protected]:47052 I0518 23:02:33.380547 1807 slave.cpp:550] Postponing registration until recovery is complete I0518 23:02:33.380976 1807 slave.cpp:396] Finished recovery I0518 23:02:33.380573 1806 status_update_manager.cpp:154] New master detected at [email protected]:47052 I0518 23:02:33.376189 1812 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:47052 I0518 23:02:33.382400 1812 hierarchical_allocator_process.hpp:268] Added framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.382779 1812 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0518 23:02:33.383249 1812 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 470.507000000000062us I0518 23:02:33.381513 1805 master.cpp:875] Attempting to register slave on janus.apache.org at slave(87)@67.195.138.60:47052 I0518 23:02:33.384219 1805 master.cpp:1831] Adding slave 201305182302-1015726915-47052-1696-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=20059 I0518 23:02:33.384762 1805 slave.cpp:595] Registered with master [email protected]:47052; given slave ID 201305182302-1015726915-47052-1696-0 I0518 23:02:33.384857 1807 hierarchical_allocator_process.hpp:395] Added slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=20059 (and cpus=3; mem=1024; ports=[31000-32000]; disk=20059 available) I0518 23:02:33.386829 1807 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.387351 1807 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.387922 1807 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305182302-1015726915-47052-1696-0 in 1.101876000000000ms I0518 23:02:33.387953 1805 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.388820 1805 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.389312 1805 sched.cpp:282] Received 1 offers I0518 23:02:33.390125 1810 master.cpp:1498] Processing reply for offer 201305182302-1015726915-47052-1696-0 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.390565 1810 master.hpp:283] Adding task 0 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.390981 1810 master.cpp:1617] Launching task 0 of framework 201305182302-1015726915-47052-1696-0000 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0 (janus.apache.org) I0518 23:02:33.391572 1810 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.391612 1805 slave.cpp:724] Got assigned task 0 for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.392647 1805 slave.cpp:822] Launching task 0 for framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.391655 1809 hierarchical_allocator_process.hpp:471] Framework 201305182302-1015726915-47052-1696-0000 left cpus=2; mem=768; ports=[31000-32000]; disk=20059 unused on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.396042 1805 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SchedulerFailover_gmQbqa/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/ff908253-866d-4d78-b4ba-77362ce5ebda' I0518 23:02:33.396222 1805 slave.cpp:933] Queuing task '0' for executor default of framework '201305182302-1015726915-47052-1696-0000 I0518 23:02:33.396471 1805 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_SchedulerFailover_gmQbqa/slaves/201305182302-1015726915-47052-1696-0/frameworks/201305182302-1015726915-47052-1696-0000/executors/default/runs/ff908253-866d-4d78-b4ba-77362ce5ebda' I0518 23:02:33.396337 1812 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:47052 with pid 1696 I0518 23:02:33.397939 1812 slave.cpp:1385] Got registration for executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.398418 1812 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.398437 1805 exec.cpp:194] Executor registered on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.399595 1805 exec.cpp:258] Executor asked to run task '0' I0518 23:02:33.400368 1807 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000' I0518 23:02:33.400676 1807 master.cpp:395] Framework 201305182302-1015726915-47052-1696-0000 disconnected I0518 23:02:33.401123 1807 master.cpp:407] Giving framework 201305182302-1015726915-47052-1696-0000 100.000000000000000ms to failover I0518 23:02:33.401192 1810 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.401523 1809 sched.cpp:182] New master at [email protected]:47052 I0518 23:02:33.403275 1811 master.cpp:588] Re-registering framework 201305182302-1015726915-47052-1696-0000 at scheduler(78)@67.195.138.60:47052 I0518 23:02:33.404304 1811 master.cpp:607] Framework 201305182302-1015726915-47052-1696-0000 failed over I0518 23:02:33.404758 1811 sched.cpp:217] Framework registered with 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.404769 1805 slave.cpp:1269] Updating framework 201305182302-1015726915-47052-1696-0000 pid to scheduler(78)@67.195.138.60:47052 I0518 23:02:33.404819 1807 hierarchical_allocator_process.hpp:327] Activated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.406213 1807 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.406754 1807 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.407452 1807 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.251140000000000ms I0518 23:02:33.407491 1812 master.hpp:305] Adding offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.408464 1812 master.cpp:1265] Sending 1 offers to framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.408990 1812 sched.cpp:282] Received 1 offers I0518 23:02:33.409600 1812 sched.cpp:422] Stopping framework '201305182302-1015726915-47052-1696-0000' I0518 23:02:33.409951 1812 master.cpp:395] Framework 201305182302-1015726915-47052-1696-0000 disconnected I0518 23:02:33.410404 1812 master.cpp:407] Giving framework 201305182302-1015726915-47052-1696-0000 100.000000000000000ms to failover I0518 23:02:33.411588 1812 master.hpp:315] Removing offer 201305182302-1015726915-47052-1696-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=20059 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.410466 1805 hierarchical_allocator_process.hpp:359] Deactivated framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.413123 1805 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=20059 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.511874 1808 master.cpp:1197] Framework failover timeout, removing framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.512012 1808 master.hpp:295] Removing task 0 with resources cpus=1; mem=256 on slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.512053 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by [email protected]:47052 I0518 23:02:33.512485 1809 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=20059) on slave 201305182302-1015726915-47052-1696-0 from framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.512601 1696 master.cpp:384] Master terminating I0518 23:02:33.514106 1696 master.cpp:206] Shutting down master I0518 23:02:33.512768 1811 slave.cpp:1129] Shutting down framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.515049 1811 slave.cpp:2302] Shutting down executor 'default' of framework 201305182302-1015726915-47052-1696-0000 I0518 23:02:33.514669 1808 hierarchical_allocator_process.hpp:423] Removed slave 201305182302-1015726915-47052-1696-0 I0518 23:02:33.515532 1811 slave.cpp:446] Slave terminating I0518 23:02:33.516458 1811 slave.cpp:1104] Asked to shut down framework 201305182302-1015726915-47052-1696-0000 by @0.0.0.0:0 W0518 23:02:33.516957 1811 slave.cpp:1125] Ignoring shutdown framework 201305182302-1015726915-47052-1696-0000 because it is terminating [ OK ] AllocatorTest/0.SchedulerFailover (144 ms) [ RUN ] AllocatorTest/0.FrameworkExited I0518 23:02:33.515563 1810 exec.cpp:323] Executor asked to shutdown pure virtual method called terminate called without an active exception /bin/bash: line 5: 1696 Aborted ${dir}$tst 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 I0518 23:02:36.190976 5418 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
