See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/326/>
------------------------------------------ [...truncated 13341 lines...] I0519 12:24:38.037094 25788 slave.cpp:595] Registered with master [email protected]:37283; given slave ID 201305191224-982172483-37283-25588-0 I0519 12:24:38.037199 25786 hierarchical_allocator_process.hpp:395] Added slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=23057 (and cpus=2; mem=1024; ports=[31000-32000]; disk=23057 available) I0519 12:24:38.038710 25786 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.039211 25786 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.039764 25786 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305191224-982172483-37283-25588-0 in 1.064388000000000ms I0519 12:24:38.039818 25788 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.040647 25788 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.041143 25791 sched.cpp:282] Received 1 offers I0519 12:24:38.041946 25795 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0000' I0519 12:24:38.042068 25788 master.cpp:681] Asked to unregister framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.042803 25795 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by [email protected]:37283 I0519 12:24:38.042855 25789 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.042858 25788 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 W0519 12:24:38.044088 25795 slave.cpp:1119] Cannot shut down unknown framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.045322 25793 hierarchical_allocator_process.hpp:312] Removed framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.045639 25786 sched.cpp:182] New master at [email protected]:37283 I0519 12:24:38.046653 25793 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=23057 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.047179 25789 master.cpp:553] Registering framework 201305191224-982172483-37283-25588-0001 at scheduler(76)@67.195.138.58:37283 I0519 12:24:38.048035 25786 sched.cpp:217] Framework registered with 201305191224-982172483-37283-25588-0001 I0519 12:24:38.048120 25791 hierarchical_allocator_process.hpp:268] Added framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.049324 25791 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.049785 25791 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.050369 25791 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.062970000000000ms I0519 12:24:38.050434 25789 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.051326 25789 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.051791 25789 sched.cpp:282] Received 1 offers I0519 12:24:38.052497 25786 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0001' I0519 12:24:38.053560 25787 master.cpp:681] Asked to unregister framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.054147 25787 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.054185 25786 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.055114 25786 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=23057 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.054719 25588 master.cpp:384] Master terminating I0519 12:24:38.056054 25588 master.cpp:206] Shutting down master I0519 12:24:38.054203 25791 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0001 by [email protected]:37283 W0519 12:24:38.057250 25791 slave.cpp:1119] Cannot shut down unknown framework 201305191224-982172483-37283-25588-0001 I0519 12:24:38.057842 25791 slave.cpp:446] Slave terminating I0519 12:24:38.056643 25787 hierarchical_allocator_process.hpp:423] Removed slave 201305191224-982172483-37283-25588-0 [ OK ] AllocatorTest/0.OutOfOrderDispatch (35 ms) [ RUN ] AllocatorTest/0.SchedulerFailover I0519 12:24:38.061969 25788 master.cpp:227] Master started on 67.195.138.58:37283 I0519 12:24:38.062055 25788 master.cpp:242] Master ID: 201305191224-982172483-37283-25588 I0519 12:24:38.062856 25790 sched.cpp:182] New master at [email protected]:37283 W0519 12:24:38.062896 25795 master.cpp:82] No whitelist given. Advertising offers for all slaves I0519 12:24:38.062988 25791 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:37283 I0519 12:24:38.063119 25788 master.cpp:510] Elected as master! I0519 12:24:38.062484 25786 slave.cpp:215] Slave started on 87)@67.195.138.58:37283 I0519 12:24:38.097646 25786 slave.cpp:216] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=23057 I0519 12:24:38.090898 25788 master.cpp:553] Registering framework 201305191224-982172483-37283-25588-0000 at scheduler(77)@67.195.138.58:37283 I0519 12:24:38.114215 25787 sched.cpp:217] Framework registered with 201305191224-982172483-37283-25588-0000 I0519 12:24:38.114138 25786 slave.cpp:535] New master detected at [email protected]:37283 I0519 12:24:38.114270 25795 hierarchical_allocator_process.hpp:268] Added framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.115833 25795 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0519 12:24:38.116363 25795 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 530.288000000000125us I0519 12:24:38.115347 25786 slave.cpp:550] Postponing registration until recovery is complete I0519 12:24:38.117413 25786 slave.cpp:396] Finished recovery I0519 12:24:38.115361 25788 status_update_manager.cpp:154] New master detected at [email protected]:37283 I0519 12:24:38.118629 25789 master.cpp:875] Attempting to register slave on quirinus.apache.org at slave(87)@67.195.138.58:37283 I0519 12:24:38.119493 25789 master.cpp:1831] Adding slave 201305191224-982172483-37283-25588-0 at quirinus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=23057 I0519 12:24:38.120023 25790 slave.cpp:595] Registered with master [email protected]:37283; given slave ID 201305191224-982172483-37283-25588-0 I0519 12:24:38.120163 25789 hierarchical_allocator_process.hpp:395] Added slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=23057 (and cpus=3; mem=1024; ports=[31000-32000]; disk=23057 available) I0519 12:24:38.121054 25789 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.121516 25789 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.122081 25789 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305191224-982172483-37283-25588-0 in 1.047485000000000ms I0519 12:24:38.122115 25790 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.122983 25790 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.123472 25795 sched.cpp:282] Received 1 offers I0519 12:24:38.124107 25790 master.cpp:1498] Processing reply for offer 201305191224-982172483-37283-25588-0 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.124541 25790 master.hpp:283] Adding task 0 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.124953 25790 master.cpp:1617] Launching task 0 of framework 201305191224-982172483-37283-25588-0000 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) I0519 12:24:38.125473 25795 slave.cpp:724] Got assigned task 0 for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.126101 25795 slave.cpp:822] Launching task 0 for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.125615 25786 hierarchical_allocator_process.hpp:471] Framework 201305191224-982172483-37283-25588-0000 left cpus=2; mem=768; ports=[31000-32000]; disk=23057 unused on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.125542 25790 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.127928 25795 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_SchedulerFailover_SKslWe/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0000/executors/default/runs/23e98ec6-e2bb-494f-8cc2-4ea2e800f9fa' I0519 12:24:38.128093 25795 slave.cpp:933] Queuing task '0' for executor default of framework '201305191224-982172483-37283-25588-0000 I0519 12:24:38.128350 25795 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_SchedulerFailover_SKslWe/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0000/executors/default/runs/23e98ec6-e2bb-494f-8cc2-4ea2e800f9fa' I0519 12:24:38.128196 25787 exec.cpp:170] Executor started at: executor(27)@67.195.138.58:37283 with pid 25588 I0519 12:24:38.129328 25787 slave.cpp:1385] Got registration for executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.129828 25787 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.129886 25788 exec.cpp:194] Executor registered on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.130820 25788 exec.cpp:258] Executor asked to run task '0' I0519 12:24:38.181669 25795 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0000' I0519 12:24:38.182617 25787 master.cpp:395] Framework 201305191224-982172483-37283-25588-0000 disconnected I0519 12:24:38.183020 25787 master.cpp:407] Giving framework 201305191224-982172483-37283-25588-0000 100.000000000000000ms to failover I0519 12:24:38.183506 25795 sched.cpp:182] New master at [email protected]:37283 I0519 12:24:38.184038 25795 master.cpp:588] Re-registering framework 201305191224-982172483-37283-25588-0000 at scheduler(78)@67.195.138.58:37283 I0519 12:24:38.184509 25795 master.cpp:607] Framework 201305191224-982172483-37283-25588-0000 failed over I0519 12:24:38.183092 25789 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.185178 25795 slave.cpp:1269] Updating framework 201305191224-982172483-37283-25588-0000 pid to scheduler(78)@67.195.138.58:37283 I0519 12:24:38.185181 25788 sched.cpp:217] Framework registered with 201305191224-982172483-37283-25588-0000 I0519 12:24:38.185575 25789 hierarchical_allocator_process.hpp:327] Activated framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.187177 25789 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.187672 25789 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.188298 25789 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.138527000000000ms I0519 12:24:38.188400 25786 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.189323 25786 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.189868 25786 sched.cpp:282] Received 1 offers I0519 12:24:38.190758 25787 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0000' I0519 12:24:38.190906 25789 master.cpp:395] Framework 201305191224-982172483-37283-25588-0000 disconnected I0519 12:24:38.191331 25789 master.cpp:407] Giving framework 201305191224-982172483-37283-25588-0000 100.000000000000000ms to failover I0519 12:24:38.191891 25789 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.191385 25787 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.192852 25787 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=23057 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.292835 25793 master.cpp:1197] Framework failover timeout, removing framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.292975 25788 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by [email protected]:37283 I0519 12:24:38.293004 25793 master.hpp:295] Removing task 0 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:38.293433 25788 slave.cpp:1129] Shutting down framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.294342 25788 slave.cpp:2302] Shutting down executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.294127 25588 master.cpp:384] Master terminating I0519 12:24:38.294054 25793 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0000 I0519 12:24:38.294847 25795 exec.cpp:323] Executor asked to shutdown I0519 12:24:38.295361 25588 master.cpp:206] Shutting down master I0519 12:24:38.295394 25791 slave.cpp:491] Slave asked to shut down by [email protected]:37283 I0519 12:24:38.297268 25791 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by [email protected]:37283 W0519 12:24:38.297709 25791 slave.cpp:1125] Ignoring shutdown framework 201305191224-982172483-37283-25588-0000 because it is terminating I0519 12:24:38.298275 25791 slave.cpp:446] Slave terminating I0519 12:24:38.299093 25791 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by @0.0.0.0:0 W0519 12:24:38.299598 25791 slave.cpp:1125] Ignoring shutdown framework 201305191224-982172483-37283-25588-0000 because it is terminating I0519 12:24:38.296849 25788 hierarchical_allocator_process.hpp:423] Removed slave 201305191224-982172483-37283-25588-0 [ OK ] AllocatorTest/0.SchedulerFailover (240 ms) [ RUN ] AllocatorTest/0.FrameworkExited I0519 12:24:38.340317 25793 master.cpp:227] Master started on 67.195.138.58:37283 I0519 12:24:38.341022 25795 slave.cpp:215] Slave started on 88)@67.195.138.58:37283 I0519 12:24:38.341523 25787 sched.cpp:182] New master at [email protected]:37283 I0519 12:24:38.365464 25793 master.cpp:242] Master ID: 201305191224-982172483-37283-25588 I0519 12:24:38.365921 25795 slave.cpp:216] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=23057 W0519 12:24:38.367100 25786 master.cpp:82] No whitelist given. Advertising offers for all slaves I0519 12:24:38.367204 25788 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:37283 I0519 12:24:38.367296 25793 master.cpp:510] Elected as master! I0519 12:24:38.368052 25795 slave.cpp:535] New master detected at [email protected]:37283 I0519 12:24:38.626097 25790 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0519 12:24:38.678797 25793 master.cpp:553] Registering framework 201305191224-982172483-37283-25588-0000 at scheduler(79)@67.195.138.58:37283 I0519 12:24:38.679306 25795 slave.cpp:550] Postponing registration until recovery is complete I0519 12:24:38.679316 25791 status_update_manager.cpp:154] New master detected at [email protected]:37283 I0519 12:24:38.790294 25790 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 164.224879000000016ms I0519 12:24:38.901000 25793 sched.cpp:217] Framework registered with 201305191224-982172483-37283-25588-0000 I0519 12:24:39.005853 25795 slave.cpp:396] Finished recovery I0519 12:24:39.237221 25790 hierarchical_allocator_process.hpp:268] Added framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.541437 25790 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0519 12:24:39.450355 25795 master.cpp:875] Attempting to register slave on quirinus.apache.org at slave(88)@67.195.138.58:37283 I0519 12:24:39.541957 25790 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 520.456999999999994us I0519 12:24:39.542490 25795 master.cpp:1831] Adding slave 201305191224-982172483-37283-25588-0 at quirinus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=23057 I0519 12:24:39.543031 25790 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0519 12:24:39.544190 25789 slave.cpp:595] Registered with master [email protected]:37283; given slave ID 201305191224-982172483-37283-25588-0 I0519 12:24:39.544607 25790 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.576822000000000ms I0519 12:24:39.545667 25790 hierarchical_allocator_process.hpp:395] Added slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=23057 (and cpus=3; mem=1024; ports=[31000-32000]; disk=23057 available) I0519 12:24:39.546136 25790 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.546669 25790 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.547301 25790 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201305191224-982172483-37283-25588-0 in 1.173827000000000ms I0519 12:24:39.547363 25789 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.548303 25789 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.548833 25786 sched.cpp:282] Received 1 offers I0519 12:24:39.549680 25789 master.cpp:1498] Processing reply for offer 201305191224-982172483-37283-25588-0 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.550937 25789 master.hpp:283] Adding task 0 with resources cpus=2; mem=512 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.552258 25789 master.cpp:1617] Launching task 0 of framework 201305191224-982172483-37283-25588-0000 with resources cpus=2; mem=512 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) I0519 12:24:39.552774 25786 slave.cpp:724] Got assigned task 0 for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.552839 25789 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.552901 25791 hierarchical_allocator_process.hpp:471] Framework 201305191224-982172483-37283-25588-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=23057 unused on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.553441 25786 slave.cpp:822] Launching task 0 for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.554429 25791 hierarchical_allocator_process.hpp:497] Framework 201305191224-982172483-37283-25588-0000 filtered slave 201305191224-982172483-37283-25588-0 for 5.000000000000000secs I0519 12:24:39.556346 25786 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_FrameworkExited_sYWJsw/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0000/executors/default/runs/ca5418d0-d2df-466d-be11-4472ce93ed5e' I0519 12:24:39.581418 25786 slave.cpp:933] Queuing task '0' for executor default of framework '201305191224-982172483-37283-25588-0000 I0519 12:24:39.581578 25793 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_FrameworkExited_sYWJsw/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0000/executors/default/runs/ca5418d0-d2df-466d-be11-4472ce93ed5e' I0519 12:24:39.581599 25789 exec.cpp:170] Executor started at: executor(28)@67.195.138.58:37283 with pid 25588 I0519 12:24:39.595732 25791 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.609999 25789 slave.cpp:1385] Got registration for executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.611098 25789 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.610538 25791 hierarchical_allocator_process.hpp:756] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 for framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.611122 25793 exec.cpp:194] Executor registered on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.612141 25791 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 16.424856999999999ms I0519 12:24:39.612704 25793 exec.cpp:258] Executor asked to run task '0' I0519 12:24:39.614689 25793 sched.cpp:182] New master at [email protected]:37283 I0519 12:24:39.614892 25789 master.cpp:553] Registering framework 201305191224-982172483-37283-25588-0001 at scheduler(80)@67.195.138.58:37283 I0519 12:24:39.615345 25793 sched.cpp:217] Framework registered with 201305191224-982172483-37283-25588-0001 I0519 12:24:39.615377 25789 hierarchical_allocator_process.hpp:268] Added framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.616617 25789 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.617102 25789 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.617755 25789 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.148643000000000ms I0519 12:24:39.617817 25788 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.618772 25788 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.619357 25787 sched.cpp:282] Received 1 offers I0519 12:24:39.620004 25790 master.cpp:1498] Processing reply for offer 201305191224-982172483-37283-25588-1 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) for framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.620141 25789 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0000' I0519 12:24:39.621266 25790 master.hpp:283] Adding task 0 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.623056 25790 master.cpp:1617] Launching task 0 of framework 201305191224-982172483-37283-25588-0001 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 (quirinus.apache.org) I0519 12:24:39.623636 25793 slave.cpp:724] Got assigned task 0 for framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.623697 25790 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-1 with resources cpus=1; mem=512; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.624680 25790 master.cpp:681] Asked to unregister framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.624290 25793 slave.cpp:822] Launching task 0 for framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.623764 25791 hierarchical_allocator_process.hpp:471] Framework 201305191224-982172483-37283-25588-0001 left mem=256; ports=[31000-32000]; disk=23057 unused on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.625202 25790 master.hpp:295] Removing task 0 with resources cpus=2; mem=512 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.626291 25791 hierarchical_allocator_process.hpp:497] Framework 201305191224-982172483-37283-25588-0001 filtered slave 201305191224-982172483-37283-25588-0 for 5.000000000000000secs I0519 12:24:39.627271 25791 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.627818 25791 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.628352 25791 hierarchical_allocator_process.hpp:312] Removed framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.627135 25793 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_FrameworkExited_sYWJsw/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0001/executors/default/runs/8b7377e2-4b46-4b68-9a65-453178b6830c' I0519 12:24:39.629461 25793 slave.cpp:933] Queuing task '0' for executor default of framework '201305191224-982172483-37283-25588-0001 I0519 12:24:39.629621 25790 exec.cpp:170] Executor started at: executor(29)@67.195.138.58:37283 with pid 25588 I0519 12:24:39.629885 25793 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by [email protected]:37283 I0519 12:24:39.630863 25793 slave.cpp:1129] Shutting down framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.631376 25793 slave.cpp:2302] Shutting down executor 'default' of framework 201305191224-982172483-37283-25588-0000 I0519 12:24:39.631922 25789 exec.cpp:323] Executor asked to shutdown I0519 12:24:39.631958 25793 slave.cpp:517] Successfully attached file '/tmp/AllocatorTest_0_FrameworkExited_sYWJsw/slaves/201305191224-982172483-37283-25588-0/frameworks/201305191224-982172483-37283-25588-0001/executors/default/runs/8b7377e2-4b46-4b68-9a65-453178b6830c' I0519 12:24:39.632961 25793 slave.cpp:1385] Got registration for executor 'default' of framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.633498 25793 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.633534 25789 exec.cpp:194] Executor registered on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.634523 25789 exec.cpp:258] Executor asked to run task '0' I0519 12:24:39.663306 25790 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.663372 25790 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.663822 25790 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 530.051000000000045us I0519 12:24:39.663890 25788 master.hpp:305] Adding offer 201305191224-982172483-37283-25588-2 with resources cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.664652 25788 master.cpp:1265] Sending 1 offers to framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.665179 25795 sched.cpp:282] Received 1 offers I0519 12:24:39.665858 25788 sched.cpp:422] Stopping framework '201305191224-982172483-37283-25588-0001' I0519 12:24:39.666116 25790 master.cpp:681] Asked to unregister framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.666556 25790 master.hpp:295] Removing task 0 with resources cpus=1; mem=256 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.667073 25790 master.hpp:315] Removing offer 201305191224-982172483-37283-25588-2 with resources cpus=2; mem=768; ports=[31000-32000]; disk=23057 on slave 201305191224-982172483-37283-25588-0 I0519 12:24:39.666609 25789 hierarchical_allocator_process.hpp:359] Deactivated framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.667986 25789 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.667630 25588 master.cpp:384] Master terminating I0519 12:24:39.666596 25795 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0001 by [email protected]:37283 I0519 12:24:39.668521 25789 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=23057 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=23057) on slave 201305191224-982172483-37283-25588-0 from framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.668951 25588 master.cpp:206] Shutting down master I0519 12:24:39.669396 25795 slave.cpp:1129] Shutting down framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.670825 25795 slave.cpp:2302] Shutting down executor 'default' of framework 201305191224-982172483-37283-25588-0001 I0519 12:24:39.671299 25795 slave.cpp:446] Slave terminating I0519 12:24:39.671754 25795 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0001 by @0.0.0.0:0 W0519 12:24:39.672258 25795 slave.cpp:1125] Ignoring shutdown framework 201305191224-982172483-37283-25588-0001 because it is terminating I0519 12:24:39.672886 25795 slave.cpp:1104] Asked to shut down framework 201305191224-982172483-37283-25588-0000 by @0.0.0.0:0 W0519 12:24:39.673738 25795 slave.cpp:1125] Ignoring shutdown framework 201305191224-982172483-37283-25588-0000 because it is terminating I0519 12:24:39.671325 25793 exec.cpp:323] Executor asked to shutdown pure virtual method called terminate called without an active exception /bin/bash: line 5: 25588 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-In-Src-Set-JAVA_HOME/ws/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make: *** [check-recursive] Error 1 I0519 12:24:40.063529 29870 exec.cpp:83] Committing suicide by killing the process group I0519 12:24:40.065134 29862 exec.cpp:83] Committing suicide by killing the process group I0519 12:24:40.065141 29856 exec.cpp:83] Committing suicide by killing the process group Build step 'Execute shell' marked build as failure
