See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1087/>
------------------------------------------ [...truncated 15816 lines...] I0628 15:56:47.176548 7826 exec.cpp:290] Executor received status update acknowledgement 6d2273e6-0325-4fd2-b313-279078ac52de for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.176676 7827 status_update_manager.cpp:360] Received status update acknowledgement 6d2273e6-0325-4fd2-b313-279078ac52de for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.176895 7824 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 6d2273e6-0325-4fd2-b313-279078ac52de for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177011 7801 master.cpp:385] Master terminating I0628 15:56:47.177124 7801 master.cpp:207] Shutting down master I0628 15:56:47.177201 7826 slave.cpp:1883] [email protected]:36742 exited W0628 15:56:47.177249 7826 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 15:56:47.177297 7801 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:47.177266 7823 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177340 7826 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by [email protected]:36742 I0628 15:56:47.177561 7826 slave.cpp:1136] Shutting down framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177623 7823 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19149) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177646 7826 slave.cpp:2327] Shutting down executor 'default' of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177801 7823 hierarchical_allocator_process.hpp:367] Removed framework 201306281556-143311683-36742-7801-0000 I0628 15:56:47.177857 7828 exec.cpp:323] Executor asked to shutdown I0628 15:56:47.178652 7828 master.cpp:228] Master started on 67.195.138.8:36742 I0628 15:56:47.178724 7828 master.cpp:243] Master ID: 201306281556-143311683-36742-7801 I0628 15:56:47.180178 7826 detector.cpp:420] Master detector (slave(99)@67.195.138.8:36742) found 0 registered masters I0628 15:56:47.180253 7829 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:36742) found 0 registered masters I0628 15:56:47.180850 7825 detector.cpp:234] Master detector ([email protected]:36742) connected to ZooKeeper ... I0628 15:56:47.185730 7826 detector.cpp:441] Master detector (slave(99)@67.195.138.8:36742) couldn't find any masters W0628 15:56:47.185811 7827 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 15:56:47.185834 7829 detector.cpp:441] Master detector (scheduler(90)@67.195.138.8:36742) couldn't find any masters I0628 15:56:47.185925 7825 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0628 15:56:47.186028 7823 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:36742 I0628 15:56:47.186071 7826 slave.cpp:562] Lost master(s) ... waiting I0628 15:56:47.186286 7822 sched.cpp:194] No master detected, waiting for another master I0628 15:56:47.187988 7825 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0628 15:56:47.188357 7829 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:36742) found 1 registered masters I0628 15:56:47.188539 7822 detector.cpp:420] Master detector (slave(99)@67.195.138.8:36742) found 1 registered masters I0628 15:56:47.188806 7825 detector.cpp:420] Master detector ([email protected]:36742) found 1 registered masters I0628 15:56:47.194286 7829 detector.cpp:467] Master detector (scheduler(90)@67.195.138.8:36742) got new master pid: [email protected]:36742 I0628 15:56:47.194411 7829 sched.cpp:177] New master at [email protected]:36742 I0628 15:56:47.194476 7822 detector.cpp:467] Master detector (slave(99)@67.195.138.8:36742) got new master pid: [email protected]:36742 W0628 15:56:47.194511 7827 master.cpp:591] Ignoring re-register framework message since not elected yet I0628 15:56:47.194653 7825 detector.cpp:467] Master detector ([email protected]:36742) got new master pid: [email protected]:36742 I0628 15:56:47.194706 7823 slave.cpp:528] New master detected at [email protected]:36742 I0628 15:56:47.194823 7825 master.cpp:526] Elected as master! I0628 15:56:47.194875 7826 status_update_manager.cpp:155] New master detected at [email protected]:36742 I0628 15:56:48.170889 7828 master.cpp:604] Re-registering framework 201306281556-143311683-36742-7801-0000 at scheduler(90)@67.195.138.8:36742 I0628 15:56:48.171073 7824 sched.cpp:246] Framework re-registered with 201306281556-143311683-36742-7801-0000 I0628 15:56:48.171109 7828 hierarchical_allocator_process.hpp:327] Added framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.171228 7828 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 15:56:48.171299 7828 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 70.86us I0628 15:56:48.186911 7822 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 15:56:48.186985 7822 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 75.263us I0628 15:56:48.195159 7827 master.cpp:963] Attempting to re-register slave 201306281556-143311683-36742-7801-0 at slave(99)@67.195.138.8:36742 (minerva.apache.org) I0628 15:56:48.195207 7827 master.cpp:1851] Adding slave 201306281556-143311683-36742-7801-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 I0628 15:56:48.195322 7826 slave.cpp:629] Re-registered with master [email protected]:36742 I0628 15:56:48.195369 7827 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 W0628 15:56:48.195562 7829 slave.cpp:1272] Ignoring updating pid for framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:48.195663 7822 hierarchical_allocator_process.hpp:449] Added slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 (and cpus=1; mem=524; ports=[31000-32000]; disk=19149 available) I0628 15:56:48.195817 7822 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.195965 7822 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281556-143311683-36742-7801-0 in 181.219us I0628 15:56:48.196027 7826 master.hpp:313] Adding offer 201306281556-143311683-36742-7801-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:48.196120 7826 master.cpp:1239] Sending 1 offers to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.196437 7826 master.cpp:385] Master terminating I0628 15:56:48.196444 7827 sched.cpp:427] Stopping framework '201306281556-143311683-36742-7801-0000' I0628 15:56:48.196528 7823 slave.cpp:484] Slave asked to shut down by [email protected]:36742 I0628 15:56:48.196537 7801 master.cpp:207] Shutting down master I0628 15:56:48.196786 7801 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:48.196794 7822 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.196964 7801 master.hpp:323] Removing offer 201306281556-143311683-36742-7801-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:48.197072 7829 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.196655 7823 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by [email protected]:36742 W0628 15:56:48.197228 7823 slave.cpp:1132] Ignoring shutdown framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:48.205646 7823 slave.cpp:439] Slave terminating I0628 15:56:48.198468 7826 detector.cpp:420] Master detector (slave(99)@67.195.138.8:36742) found 0 registered masters I0628 15:56:48.205828 7826 detector.cpp:441] Master detector (slave(99)@67.195.138.8:36742) couldn't find any masters I0628 15:56:48.205767 7823 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by @0.0.0.0:0 W0628 15:56:48.206044 7823 slave.cpp:1132] Ignoring shutdown framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:48.197237 7829 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=19149 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19149) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.206367 7829 hierarchical_allocator_process.hpp:367] Removed framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.206434 7829 hierarchical_allocator_process.hpp:477] Removed slave 201306281556-143311683-36742-7801-0 [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2054 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0628 15:56:48.207195 7823 master.cpp:228] Master started on 67.195.138.8:36742 I0628 15:56:48.207254 7823 master.cpp:243] Master ID: 201306281556-143311683-36742-7801 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src> 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:47283 sessionTimeout=10000 watcher=0x2abb5f3e9b60 sessionId=0 sessionPasswd=<null> context=0x2abb8800c5d0 flags=0 2013-06-28 15:56:48,252:7801(0x2abb626dd700):ZOO_DEBUG@start_threads@152: starting threads... 2013-06-28 15:56:48,253:7801(0x2abc71176700):ZOO_DEBUG@do_io@279: started IO thread 2013-06-28 15:56:48,253:7801(0x2abc71377700):ZOO_DEBUG@do_completion@326: started completion thread I0628 15:56:48.208644 7824 detector.cpp:234] Master detector ([email protected]:36742) connected to ZooKeeper ... I0628 15:56:48.248466 7829 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:36742 W0628 15:56:48.248481 7822 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 15:56:48.252524 7826 slave.cpp:112] Slave started on 100)@67.195.138.8:36742 I0628 15:56:48.254101 7828 detector.cpp:234] Master detector (slave(100)@67.195.138.8:36742) connected to ZooKeeper ... I0628 15:56:48.254555 7823 detector.cpp:234] Master detector (scheduler(91)@67.195.138.8:36742) connected to ZooKeeper ... I0628 15:56:48.261678 7824 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0628 15:56:48.261997 7828 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0628 15:56:48.262030 7826 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=19149 I0628 15:56:48.262099 7823 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0628 15:56:48.262823 7822 slave.cpp:389] Finished recovery I0628 15:56:48.264338 7824 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0628 15:56:48.264493 7828 detector.cpp:420] Master detector (slave(100)@67.195.138.8:36742) found 1 registered masters I0628 15:56:48.264744 7823 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:36742) found 1 registered masters I0628 15:56:48.264917 7824 detector.cpp:420] Master detector ([email protected]:36742) found 1 registered masters I0628 15:56:48.270197 7828 detector.cpp:467] Master detector (slave(100)@67.195.138.8:36742) got new master pid: [email protected]:36742 I0628 15:56:48.270315 7828 slave.cpp:528] New master detected at [email protected]:36742 I0628 15:56:48.270406 7827 status_update_manager.cpp:155] New master detected at [email protected]:36742 W0628 15:56:48.270467 7825 master.cpp:872] Ignoring register slave message from minerva.apache.org since not elected yet I0628 15:56:48.270454 7824 detector.cpp:467] Master detector ([email protected]:36742) got new master pid: [email protected]:36742 I0628 15:56:48.270699 7824 master.cpp:526] Elected as master! I0628 15:56:48.270792 7823 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:36742) got new master pid: [email protected]:36742 I0628 15:56:48.270874 7823 sched.cpp:177] New master at [email protected]:36742 I0628 15:56:48.271005 7823 master.cpp:569] Registering framework 201306281556-143311683-36742-7801-0000 at scheduler(91)@67.195.138.8:36742 I0628 15:56:48.271086 7824 hierarchical_allocator_process.hpp:327] Added framework 201306281556-143311683-36742-7801-0000 I0628 15:56:48.271111 7824 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 15:56:48.271224 7824 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 113.206us I0628 15:56:48.271131 7823 sched.cpp:222] Framework registered with 201306281556-143311683-36742-7801-0000 I0628 15:56:49.262648 7828 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0628 15:56:49.262730 7828 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 96.798us I0628 15:56:49.270871 7823 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(100)@67.195.138.8:36742 I0628 15:56:49.270900 7823 master.cpp:1851] Adding slave 201306281556-143311683-36742-7801-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 I0628 15:56:49.270987 7828 slave.cpp:588] Registered with master [email protected]:36742; given slave ID 201306281556-143311683-36742-7801-0 I0628 15:56:49.271107 7824 hierarchical_allocator_process.hpp:449] Added slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 (and cpus=2; mem=1024; ports=[31000-32000]; disk=19149 available) I0628 15:56:49.271157 7824 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.271308 7824 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281556-143311683-36742-7801-0 in 156.014us I0628 15:56:49.271340 7823 master.hpp:313] Adding offer 201306281556-143311683-36742-7801-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.271406 7823 master.cpp:1239] Sending 1 offers to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.271646 7823 master.cpp:1472] Processing reply for offer 201306281556-143311683-36742-7801-0 on slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) for framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.271755 7823 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.271797 7823 master.cpp:1591] Launching task 0 of framework 201306281556-143311683-36742-7801-0000 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) I0628 15:56:49.271888 7824 slave.cpp:738] Got assigned task 0 for framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.271987 7823 master.hpp:323] Removing offer 201306281556-143311683-36742-7801-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.271983 7827 hierarchical_allocator_process.hpp:526] Framework 201306281556-143311683-36742-7801-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=19149 unused on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.272080 7824 slave.cpp:836] Launching task 0 for framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.272208 7827 hierarchical_allocator_process.hpp:569] Framework 201306281556-143311683-36742-7801-0000 filtered slave 201306281556-143311683-36742-7801-0 for 5secs I0628 15:56:49.273571 7824 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_63hr3T/slaves/201306281556-143311683-36742-7801-0/frameworks/201306281556-143311683-36742-7801-0000/executors/default/runs/941b9b4e-d6df-438b-b009-aa559a9a8ef1' I0628 15:56:49.273752 7824 slave.cpp:947] Queuing task '0' for executor default of framework '201306281556-143311683-36742-7801-0000 I0628 15:56:49.273869 7826 slave.cpp:510] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_63hr3T/slaves/201306281556-143311683-36742-7801-0/frameworks/201306281556-143311683-36742-7801-0000/executors/default/runs/941b9b4e-d6df-438b-b009-aa559a9a8ef1' I0628 15:56:49.273929 7822 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:36742 with pid 7801 I0628 15:56:49.274046 7825 slave.cpp:1394] Got registration for executor 'default' of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.274160 7825 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.274209 7824 exec.cpp:194] Executor registered on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.274381 7824 exec.cpp:258] Executor asked to run task '0' I0628 15:56:49.275547 7824 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.275620 7824 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 from executor(38)@67.195.138.8:36742 I0628 15:56:49.275739 7824 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 with checkpoint=false I0628 15:56:49.275774 7824 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.275908 7824 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 to [email protected]:36742 I0628 15:56:49.276011 7822 master.cpp:1022] Status update from slave(100)@67.195.138.8:36742: task 0 of framework 201306281556-143311683-36742-7801-0000 is now in state TASK_RUNNING I0628 15:56:49.276119 7823 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.276247 7823 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: fc35916b-9669-40ea-9fde-ecda94d94b88) for task 0 of framework 201306281556-143311683-36742-7801-0000 to executor(38)@67.195.138.8:36742 I0628 15:56:49.276329 7827 exec.cpp:290] Executor received status update acknowledgement fc35916b-9669-40ea-9fde-ecda94d94b88 for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.276574 7823 status_update_manager.cpp:360] Received status update acknowledgement fc35916b-9669-40ea-9fde-ecda94d94b88 for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.276643 7801 master.cpp:385] Master terminating I0628 15:56:49.276729 7829 slave.cpp:1342] Status update manager successfully handled status update acknowledgement fc35916b-9669-40ea-9fde-ecda94d94b88 for task 0 of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.276803 7801 master.cpp:207] Shutting down master I0628 15:56:49.276968 7829 slave.cpp:1883] [email protected]:36742 exited W0628 15:56:49.277010 7829 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 15:56:49.277026 7823 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.277029 7801 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:49.277108 7829 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by [email protected]:36742 I0628 15:56:49.277307 7829 slave.cpp:1136] Shutting down framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.277358 7829 slave.cpp:2327] Shutting down executor 'default' of framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.277366 7827 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19149) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:49.277477 7825 exec.cpp:323] Executor asked to shutdown I0628 15:56:49.278202 7827 master.cpp:228] Master started on 67.195.138.8:36742 I0628 15:56:49.278245 7827 master.cpp:243] Master ID: 201306281556-143311683-36742-7801 I0628 15:56:49.279021 7823 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:36742) found 0 registered masters I0628 15:56:49.279146 7826 detector.cpp:420] Master detector (slave(100)@67.195.138.8:36742) found 0 registered masters I0628 15:56:49.279909 7824 detector.cpp:234] Master detector ([email protected]:36742) connected to ZooKeeper ... I0628 15:56:49.285692 7823 detector.cpp:441] Master detector (scheduler(91)@67.195.138.8:36742) couldn't find any masters W0628 15:56:49.285754 7828 master.cpp:83] No whitelist given. Advertising offers for all slaves I0628 15:56:49.285801 7826 detector.cpp:441] Master detector (slave(100)@67.195.138.8:36742) couldn't find any masters I0628 15:56:49.285879 7825 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:36742 I0628 15:56:49.285941 7824 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0628 15:56:49.286063 7823 sched.cpp:194] No master detected, waiting for another master I0628 15:56:49.286192 7828 slave.cpp:562] Lost master(s) ... waiting I0628 15:56:49.287852 7824 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0628 15:56:49.288226 7825 detector.cpp:420] Master detector (slave(100)@67.195.138.8:36742) found 1 registered masters I0628 15:56:49.288372 7827 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:36742) found 1 registered masters I0628 15:56:49.288534 7824 detector.cpp:420] Master detector ([email protected]:36742) found 1 registered masters I0628 15:56:49.294181 7825 detector.cpp:467] Master detector (slave(100)@67.195.138.8:36742) got new master pid: [email protected]:36742 I0628 15:56:49.294378 7827 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:36742) got new master pid: [email protected]:36742 I0628 15:56:49.294389 7829 slave.cpp:528] New master detected at [email protected]:36742 I0628 15:56:49.294564 7822 sched.cpp:177] New master at [email protected]:36742 I0628 15:56:49.294620 7825 status_update_manager.cpp:155] New master detected at [email protected]:36742 I0628 15:56:49.294565 7824 detector.cpp:467] Master detector ([email protected]:36742) got new master pid: [email protected]:36742 W0628 15:56:49.294739 7828 master.cpp:918] Ignoring re-register slave message from minerva.apache.org since not elected yet I0628 15:56:49.294917 7828 master.cpp:526] Elected as master! I0628 15:56:50.271054 7827 master.cpp:963] Attempting to re-register slave 201306281556-143311683-36742-7801-0 at slave(100)@67.195.138.8:36742 (minerva.apache.org) I0628 15:56:50.271103 7827 master.cpp:1851] Adding slave 201306281556-143311683-36742-7801-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 I0628 15:56:50.271298 7827 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:50.271333 7823 slave.cpp:629] Re-registered with master [email protected]:36742 W0628 15:56:50.271366 7827 master.cpp:1943] Possibly orphaned task 0 of framework 201306281556-143311683-36742-7801-0000 running on slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) I0628 15:56:50.271782 7822 hierarchical_allocator_process.hpp:449] Added slave 201306281556-143311683-36742-7801-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19149 (and cpus=1; mem=524; ports=[31000-32000]; disk=19149 available) I0628 15:56:50.271834 7822 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 15:56:50.271886 7822 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281556-143311683-36742-7801-0 in 51.102us I0628 15:56:50.287039 7828 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0628 15:56:50.287113 7828 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 82.592us I0628 15:56:50.295202 7826 master.cpp:604] Re-registering framework 201306281556-143311683-36742-7801-0000 at scheduler(91)@67.195.138.8:36742 I0628 15:56:50.295414 7827 sched.cpp:246] Framework re-registered with 201306281556-143311683-36742-7801-0000 W0628 15:56:50.295446 7822 slave.cpp:1272] Ignoring updating pid for framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:50.295681 7823 hierarchical_allocator_process.hpp:327] Added framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.295753 7823 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.295912 7823 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 193.835us I0628 15:56:50.295985 7828 master.hpp:313] Adding offer 201306281556-143311683-36742-7801-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:50.296087 7828 master.cpp:1239] Sending 1 offers to framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.296416 7827 sched.cpp:427] Stopping framework '201306281556-143311683-36742-7801-0000' I0628 15:56:50.296422 7822 master.cpp:385] Master terminating I0628 15:56:50.296598 7825 slave.cpp:484] Slave asked to shut down by [email protected]:36742 I0628 15:56:50.296633 7801 master.cpp:207] Shutting down master I0628 15:56:50.296675 7825 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by [email protected]:36742 W0628 15:56:50.296846 7825 slave.cpp:1132] Ignoring shutdown framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:50.296869 7822 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.296880 7801 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:50.296952 7825 slave.cpp:1883] [email protected]:36742 exited W0628 15:56:50.297201 7825 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected I0628 15:56:50.297283 7825 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by [email protected]:36742 W0628 15:56:50.297334 7825 slave.cpp:1132] Ignoring shutdown framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:50.297348 7828 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.297343 7801 master.hpp:323] Removing offer 201306281556-143311683-36742-7801-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=19149 on slave 201306281556-143311683-36742-7801-0 I0628 15:56:50.297616 7828 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=19149 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19149) on slave 201306281556-143311683-36742-7801-0 from framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.298339 7801 slave.cpp:439] Slave terminating I0628 15:56:50.305732 7801 slave.cpp:1111] Asked to shut down framework 201306281556-143311683-36742-7801-0000 by @0.0.0.0:0 W0628 15:56:50.305790 7801 slave.cpp:1132] Ignoring shutdown framework 201306281556-143311683-36742-7801-0000 because it is terminating I0628 15:56:50.305794 7828 hierarchical_allocator_process.hpp:367] Removed framework 201306281556-143311683-36742-7801-0000 I0628 15:56:50.299312 7826 detector.cpp:420] Master detector (slave(100)@67.195.138.8:36742) found 0 registered masters I0628 15:56:50.305979 7826 detector.cpp:441] Master detector (slave(100)@67.195.138.8:36742) couldn't find any masters I0628 15:56:50.306002 7828 hierarchical_allocator_process.hpp:477] Removed slave 201306281556-143311683-36742-7801-0 [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2099 ms) I0628 15:56:50.307878 7801 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 47283 [----------] 2 tests from AllocatorZooKeeperTest/0 (4154 ms total) [----------] Global test environment tear-down [==========] 196 tests from 37 test cases ran. (176700 ms total) [ PASSED ] 195 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ReaperTest.TerminatedChildProcess 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-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 Build step 'Execute shell' marked build as failure
