See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1357/>
------------------------------------------ [...truncated 14290 lines...] I0712 11:53:49.965350 2976 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: c337b813-bf00-4d06-a51d-4fbd7ec44817) for task 0 of framework 201307121153-143311683-56642-2946-0000 to [email protected]:56642 I0712 11:53:49.965451 2971 master.cpp:1093] Status update from slave(100)@67.195.138.8:56642: task 0 of framework 201307121153-143311683-56642-2946-0000 is now in state TASK_RUNNING I0712 11:53:49.965528 2975 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: c337b813-bf00-4d06-a51d-4fbd7ec44817) for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.965658 2975 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: c337b813-bf00-4d06-a51d-4fbd7ec44817) for task 0 of framework 201307121153-143311683-56642-2946-0000 to executor(37)@67.195.138.8:56642 I0712 11:53:49.965829 2970 exec.cpp:290] Executor received status update acknowledgement c337b813-bf00-4d06-a51d-4fbd7ec44817 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.965883 2975 status_update_manager.cpp:360] Received status update acknowledgement c337b813-bf00-4d06-a51d-4fbd7ec44817 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.966081 2975 slave.cpp:1343] Status update manager successfully handled status update acknowledgement c337b813-bf00-4d06-a51d-4fbd7ec44817 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.966191 2975 master.cpp:436] Master terminating I0712 11:53:49.966351 2946 master.cpp:209] Shutting down master I0712 11:53:49.966384 2971 slave.cpp:1884] [email protected]:56642 exited I0712 11:53:49.966506 2970 hierarchical_allocator_process.hpp:411] Deactivated framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.966528 2946 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 W0712 11:53:49.966581 2971 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected I0712 11:53:49.966825 2971 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by [email protected]:56642 I0712 11:53:49.966869 2971 slave.cpp:1137] Shutting down framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.966869 2969 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20741) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.966959 2971 slave.cpp:2328] Shutting down executor 'default' of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.967187 2969 hierarchical_allocator_process.hpp:366] Removed framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.967187 2974 exec.cpp:323] Executor asked to shutdown I0712 11:53:49.968009 2972 master.cpp:230] Master started on 67.195.138.8:56642 I0712 11:53:49.968086 2972 master.cpp:245] Master ID: 201307121153-143311683-56642-2946 W0712 11:53:49.968299 2974 master.cpp:83] No whitelist given. Advertising offers for all slaves I0712 11:53:49.968485 2970 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:56642 I0712 11:53:49.969796 2973 detector.cpp:234] Master detector ([email protected]:56642) connected to ZooKeeper ... I0712 11:53:49.969841 2973 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0712 11:53:49.969966 2974 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56642) found 0 registered masters I0712 11:53:49.970111 2970 detector.cpp:420] Master detector (scheduler(93)@67.195.138.8:56642) found 0 registered masters I0712 11:53:49.973697 2974 detector.cpp:441] Master detector (slave(100)@67.195.138.8:56642) couldn't find any masters I0712 11:53:49.973809 2970 detector.cpp:441] Master detector (scheduler(93)@67.195.138.8:56642) couldn't find any masters I0712 11:53:49.973927 2974 slave.cpp:563] Lost master(s) ... waiting I0712 11:53:49.973997 2970 sched.cpp:194] No master detected, waiting for another master I0712 11:53:49.975222 2973 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0712 11:53:49.975705 2976 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56642) found 1 registered masters I0712 11:53:49.975842 2972 detector.cpp:420] Master detector (scheduler(93)@67.195.138.8:56642) found 1 registered masters I0712 11:53:49.976006 2973 detector.cpp:420] Master detector ([email protected]:56642) found 1 registered masters I0712 11:53:49.982276 2976 detector.cpp:467] Master detector (slave(100)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:49.982403 2976 slave.cpp:529] New master detected at [email protected]:56642 I0712 11:53:49.982446 2972 detector.cpp:467] Master detector (scheduler(93)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:49.982483 2975 status_update_manager.cpp:155] New master detected at [email protected]:56642 I0712 11:53:49.982673 2973 detector.cpp:467] Master detector ([email protected]:56642) got new master pid: [email protected]:56642 I0712 11:53:49.982733 2975 sched.cpp:177] New master at [email protected]:56642 I0712 11:53:49.982794 2973 master.cpp:583] Elected as master! I0712 11:53:49.982907 2973 master.cpp:675] Re-registering framework 201307121153-143311683-56642-2946-0000 at scheduler(93)@67.195.138.8:56642 I0712 11:53:49.983126 2969 sched.cpp:246] Framework re-registered with 201307121153-143311683-56642-2946-0000 I0712 11:53:49.983268 2973 hierarchical_allocator_process.hpp:335] Added framework 201307121153-143311683-56642-2946-0000 I0712 11:53:49.983343 2973 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0712 11:53:49.983413 2973 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 70.71us I0712 11:53:50.959470 2972 master.cpp:1034] Attempting to re-register slave 201307121153-143311683-56642-2946-0 at slave(100)@67.195.138.8:56642 (minerva.apache.org) I0712 11:53:50.959522 2972 master.cpp:1929] Adding slave 201307121153-143311683-56642-2946-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 I0712 11:53:50.959715 2976 slave.cpp:630] Re-registered with master [email protected]:56642 I0712 11:53:50.959782 2972 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 W0712 11:53:50.960011 2970 slave.cpp:1273] Ignoring updating pid for framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:50.960144 2974 hierarchical_allocator_process.hpp:448] Added slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 (and cpus=1; mem=524; ports=[31000-32000]; disk=20741 available) I0712 11:53:50.960257 2974 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.960405 2974 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307121153-143311683-56642-2946-0 in 173.309us I0712 11:53:50.960453 2971 master.hpp:327] Adding offer 201307121153-143311683-56642-2946-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:50.960526 2971 master.cpp:1310] Sending 1 offers to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.960909 2975 master.cpp:436] Master terminating I0712 11:53:50.960908 2976 sched.cpp:427] Stopping framework '201307121153-143311683-56642-2946-0000' I0712 11:53:50.961047 2946 master.cpp:209] Shutting down master I0712 11:53:50.960973 2971 slave.cpp:485] Slave asked to shut down by [email protected]:56642 I0712 11:53:50.961241 2946 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:50.961246 2975 hierarchical_allocator_process.hpp:411] Deactivated framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.961596 2946 master.hpp:337] Removing offer 201307121153-143311683-56642-2946-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:50.961648 2975 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.961383 2971 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by [email protected]:56642 W0712 11:53:50.961820 2971 slave.cpp:1133] Ignoring shutdown framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:50.961933 2975 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20741 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20741) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.961983 2971 slave.cpp:1884] [email protected]:56642 exited I0712 11:53:50.962120 2975 hierarchical_allocator_process.hpp:366] Removed framework 201307121153-143311683-56642-2946-0000 I0712 11:53:50.963445 2976 detector.cpp:420] Master detector (slave(100)@67.195.138.8:56642) found 0 registered masters W0712 11:53:50.969662 2971 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected I0712 11:53:50.969816 2975 hierarchical_allocator_process.hpp:476] Removed slave 201307121153-143311683-56642-2946-0 I0712 11:53:50.969843 2976 detector.cpp:441] Master detector (slave(100)@67.195.138.8:56642) couldn't find any masters I0712 11:53:50.969957 2971 slave.cpp:440] Slave terminating I0712 11:53:50.970039 2975 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0712 11:53:50.970196 2971 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by @0.0.0.0:0 I0712 11:53:50.970281 2975 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 243.024us W0712 11:53:50.970345 2971 slave.cpp:1133] Ignoring shutdown framework 201307121153-143311683-56642-2946-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2031 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0712 11:53:50.971809 2970 master.cpp:230] Master started on 67.195.138.8:56642 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src> 2013-07-12 11:53:50,972:2946(0x2af152306700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:33346 sessionTimeout=10000 watcher=0x2af14f204b60 sessionId=0 sessionPasswd=<null> context=0x2af16400b670 flags=0 I0712 11:53:50.971859 2970 master.cpp:245] Master ID: 201307121153-143311683-56642-2946 I0712 11:53:50.972619 2972 slave.cpp:113] Slave started on 101)@67.195.138.8:56642 I0712 11:53:50.973631 2969 detector.cpp:234] Master detector ([email protected]:56642) connected to ZooKeeper ... I0712 11:53:50.974176 2974 detector.cpp:234] Master detector (slave(101)@67.195.138.8:56642) connected to ZooKeeper ... I0712 11:53:50.974766 2976 detector.cpp:234] Master detector (scheduler(94)@67.195.138.8:56642) connected to ZooKeeper ... W0712 11:53:50.981807 2973 master.cpp:83] No whitelist given. Advertising offers for all slaves I0712 11:53:50.981850 2969 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0712 11:53:50.981869 2972 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=20741 I0712 11:53:50.981886 2971 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:56642 I0712 11:53:50.981925 2974 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0712 11:53:50.981999 2976 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0712 11:53:50.982941 2973 slave.cpp:390] Finished recovery I0712 11:53:50.984140 2976 detector.cpp:420] Master detector (scheduler(94)@67.195.138.8:56642) found 1 registered masters I0712 11:53:50.984231 2969 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0712 11:53:50.984328 2974 detector.cpp:420] Master detector (slave(101)@67.195.138.8:56642) found 1 registered masters I0712 11:53:50.984678 2976 detector.cpp:467] Master detector (scheduler(94)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:50.989904 2976 sched.cpp:177] New master at [email protected]:56642 W0712 11:53:50.989984 2976 master.cpp:606] Ignoring register framework message since not elected yet I0712 11:53:50.990468 2969 detector.cpp:420] Master detector ([email protected]:56642) found 1 registered masters I0712 11:53:50.990665 2974 detector.cpp:467] Master detector (slave(101)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:50.990761 2974 slave.cpp:529] New master detected at [email protected]:56642 I0712 11:53:50.990819 2976 status_update_manager.cpp:155] New master detected at [email protected]:56642 W0712 11:53:50.990874 2976 master.cpp:943] Ignoring register slave message from minerva.apache.org since not elected yet I0712 11:53:50.991065 2969 detector.cpp:467] Master detector ([email protected]:56642) got new master pid: [email protected]:56642 I0712 11:53:50.991161 2969 master.cpp:583] Elected as master! I0712 11:53:51.983312 2973 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0712 11:53:51.983399 2973 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 96.339us I0712 11:53:51.990627 2970 master.cpp:633] Registering framework 201307121153-143311683-56642-2946-0000 at scheduler(94)@67.195.138.8:56642 I0712 11:53:51.990756 2970 hierarchical_allocator_process.hpp:335] Added framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.990808 2975 sched.cpp:222] Framework registered with 201307121153-143311683-56642-2946-0000 I0712 11:53:51.990862 2970 hierarchical_allocator_process.hpp:704] No resources available to allocate! I0712 11:53:51.990952 2970 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 89.118us I0712 11:53:51.991637 2973 master.cpp:962] Attempting to register slave on minerva.apache.org at slave(101)@67.195.138.8:56642 I0712 11:53:51.991672 2973 master.cpp:1929] Adding slave 201307121153-143311683-56642-2946-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 I0712 11:53:51.991796 2972 slave.cpp:589] Registered with master [email protected]:56642; given slave ID 201307121153-143311683-56642-2946-0 I0712 11:53:51.991907 2970 hierarchical_allocator_process.hpp:448] Added slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 (and cpus=2; mem=1024; ports=[31000-32000]; disk=20741 available) I0712 11:53:51.992023 2970 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.992168 2970 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307121153-143311683-56642-2946-0 in 165.801us I0712 11:53:51.992243 2972 master.hpp:327] Adding offer 201307121153-143311683-56642-2946-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:51.992328 2972 master.cpp:1310] Sending 1 offers to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.992609 2972 master.cpp:1543] Processing reply for offer 201307121153-143311683-56642-2946-0 on slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) for framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.992717 2972 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:51.992758 2972 master.cpp:1662] Launching task 0 of framework 201307121153-143311683-56642-2946-0000 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) I0712 11:53:51.992879 2973 slave.cpp:739] Got assigned task 0 for framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.992959 2972 master.hpp:337] Removing offer 201307121153-143311683-56642-2946-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:51.993055 2973 slave.cpp:837] Launching task 0 for framework 201307121153-143311683-56642-2946-0000 I0712 11:53:51.993100 2972 hierarchical_allocator_process.hpp:525] Framework 201307121153-143311683-56642-2946-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=20741 unused on slave 201307121153-143311683-56642-2946-0 I0712 11:53:51.993273 2972 hierarchical_allocator_process.hpp:568] Framework 201307121153-143311683-56642-2946-0000 filtered slave 201307121153-143311683-56642-2946-0 for 5secs I0712 11:53:51.998667 2973 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_A883Sp/slaves/201307121153-143311683-56642-2946-0/frameworks/201307121153-143311683-56642-2946-0000/executors/default/runs/9c07253a-1d12-44fa-bb97-c60311006eb9' I0712 11:53:51.998826 2973 slave.cpp:948] Queuing task '0' for executor default of framework '201307121153-143311683-56642-2946-0000 I0712 11:53:51.998930 2972 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:56642 with pid 2946 I0712 11:53:52.005686 2973 slave.cpp:511] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_A883Sp/slaves/201307121153-143311683-56642-2946-0/frameworks/201307121153-143311683-56642-2946-0000/executors/default/runs/9c07253a-1d12-44fa-bb97-c60311006eb9' I0712 11:53:52.005851 2973 slave.cpp:1395] Got registration for executor 'default' of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.005962 2973 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.006002 2975 exec.cpp:194] Executor registered on slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.006206 2970 exec.cpp:258] Executor asked to run task '0' I0712 11:53:52.007483 2970 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.007570 2973 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 from executor(38)@67.195.138.8:56642 I0712 11:53:52.007709 2973 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 with checkpoint=false I0712 11:53:52.007763 2973 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.007884 2973 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 to [email protected]:56642 I0712 11:53:52.008012 2973 master.cpp:1093] Status update from slave(101)@67.195.138.8:56642: task 0 of framework 201307121153-143311683-56642-2946-0000 is now in state TASK_RUNNING I0712 11:53:52.008095 2973 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.008123 2973 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877) for task 0 of framework 201307121153-143311683-56642-2946-0000 to executor(38)@67.195.138.8:56642 I0712 11:53:52.008237 2970 exec.cpp:290] Executor received status update acknowledgement 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.008678 2975 status_update_manager.cpp:360] Received status update acknowledgement 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.008841 2946 master.cpp:436] Master terminating I0712 11:53:52.008849 2972 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 5ec008cc-a9aa-4127-99a1-3f4ee2ba7877 for task 0 of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.008919 2946 master.cpp:209] Shutting down master I0712 11:53:52.009140 2972 slave.cpp:1884] [email protected]:56642 exited W0712 11:53:52.009232 2972 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected I0712 11:53:52.009256 2969 hierarchical_allocator_process.hpp:411] Deactivated framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.009261 2946 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.009321 2972 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by [email protected]:56642 I0712 11:53:52.009552 2972 slave.cpp:1137] Shutting down framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.009636 2972 slave.cpp:2328] Shutting down executor 'default' of framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.009672 2976 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20741) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.009795 2972 exec.cpp:323] Executor asked to shutdown I0712 11:53:52.009886 2976 hierarchical_allocator_process.hpp:366] Removed framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.010807 2975 master.cpp:230] Master started on 67.195.138.8:56642 I0712 11:53:52.011030 2975 master.cpp:245] Master ID: 201307121153-143311683-56642-2946 I0712 11:53:52.012645 2970 detector.cpp:234] Master detector ([email protected]:56642) connected to ZooKeeper ... I0712 11:53:52.012822 2971 detector.cpp:420] Master detector (slave(101)@67.195.138.8:56642) found 0 registered masters I0712 11:53:52.012995 2974 detector.cpp:420] Master detector (scheduler(94)@67.195.138.8:56642) found 0 registered masters I0712 11:53:52.017699 2970 detector.cpp:251] Trying to create path '/znode' in ZooKeeper W0712 11:53:52.017768 2973 master.cpp:83] No whitelist given. Advertising offers for all slaves I0712 11:53:52.017858 2974 detector.cpp:441] Master detector (scheduler(94)@67.195.138.8:56642) couldn't find any masters I0712 11:53:52.017887 2971 detector.cpp:441] Master detector (slave(101)@67.195.138.8:56642) couldn't find any masters I0712 11:53:52.018131 2972 sched.cpp:194] No master detected, waiting for another master I0712 11:53:52.017974 2969 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : [email protected]:56642 I0712 11:53:52.018224 2971 slave.cpp:563] Lost master(s) ... waiting I0712 11:53:52.019613 2970 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0712 11:53:52.020005 2971 detector.cpp:420] Master detector (slave(101)@67.195.138.8:56642) found 1 registered masters I0712 11:53:52.020134 2972 detector.cpp:420] Master detector (scheduler(94)@67.195.138.8:56642) found 1 registered masters I0712 11:53:52.020249 2970 detector.cpp:420] Master detector ([email protected]:56642) found 1 registered masters I0712 11:53:52.026233 2972 detector.cpp:467] Master detector (scheduler(94)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:52.026347 2970 detector.cpp:467] Master detector ([email protected]:56642) got new master pid: [email protected]:56642 I0712 11:53:52.026403 2972 sched.cpp:177] New master at [email protected]:56642 I0712 11:53:52.026522 2971 detector.cpp:467] Master detector (slave(101)@67.195.138.8:56642) got new master pid: [email protected]:56642 I0712 11:53:52.026546 2973 master.cpp:583] Elected as master! I0712 11:53:52.026659 2971 slave.cpp:529] New master detected at [email protected]:56642 I0712 11:53:52.026754 2969 status_update_manager.cpp:155] New master detected at [email protected]:56642 I0712 11:53:52.026878 2972 master.cpp:1034] Attempting to re-register slave 201307121153-143311683-56642-2946-0 at slave(101)@67.195.138.8:56642 (minerva.apache.org) I0712 11:53:52.026957 2972 master.cpp:1929] Adding slave 201307121153-143311683-56642-2946-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 I0712 11:53:52.027098 2976 slave.cpp:630] Re-registered with master [email protected]:56642 I0712 11:53:52.027119 2972 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 W0712 11:53:52.027235 2972 master.cpp:2021] Possibly orphaned task 0 of framework 201307121153-143311683-56642-2946-0000 running on slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) I0712 11:53:52.027559 2976 hierarchical_allocator_process.hpp:448] Added slave 201307121153-143311683-56642-2946-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=20741 (and cpus=1; mem=524; ports=[31000-32000]; disk=20741 available) I0712 11:53:52.027647 2976 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307121153-143311683-56642-2946-0 in 6.186us I0712 11:53:52.991173 2974 master.cpp:675] Re-registering framework 201307121153-143311683-56642-2946-0000 at scheduler(94)@67.195.138.8:56642 I0712 11:53:52.991382 2976 sched.cpp:246] Framework re-registered with 201307121153-143311683-56642-2946-0000 W0712 11:53:52.991447 2973 slave.cpp:1273] Ignoring updating pid for framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:52.991561 2975 hierarchical_allocator_process.hpp:335] Added framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.991628 2975 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.991754 2975 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 138.597us I0712 11:53:52.991793 2976 master.hpp:327] Adding offer 201307121153-143311683-56642-2946-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.991899 2976 master.cpp:1310] Sending 1 offers to framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.992256 2976 master.cpp:436] Master terminating I0712 11:53:52.992254 2970 sched.cpp:427] Stopping framework '201307121153-143311683-56642-2946-0000' I0712 11:53:52.992331 2976 slave.cpp:485] Slave asked to shut down by [email protected]:56642 I0712 11:53:52.992360 2946 master.cpp:209] Shutting down master I0712 11:53:52.992482 2976 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by [email protected]:56642 W0712 11:53:52.992641 2976 slave.cpp:1133] Ignoring shutdown framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:52.992719 2976 slave.cpp:1884] [email protected]:56642 exited I0712 11:53:52.992779 2946 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.992645 2972 hierarchical_allocator_process.hpp:411] Deactivated framework 201307121153-143311683-56642-2946-0000 W0712 11:53:52.992801 2976 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected I0712 11:53:52.993007 2976 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by [email protected]:56642 W0712 11:53:52.993084 2976 slave.cpp:1133] Ignoring shutdown framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:52.993176 2972 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.993203 2946 master.hpp:337] Removing offer 201307121153-143311683-56642-2946-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=20741 on slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.997730 2972 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=20741 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=20741) on slave 201307121153-143311683-56642-2946-0 from framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.997908 2974 hierarchical_allocator_process.hpp:366] Removed framework 201307121153-143311683-56642-2946-0000 I0712 11:53:52.998059 2972 hierarchical_allocator_process.hpp:476] Removed slave 201307121153-143311683-56642-2946-0 I0712 11:53:52.998538 2946 slave.cpp:440] Slave terminating I0712 11:53:52.998579 2946 slave.cpp:1112] Asked to shut down framework 201307121153-143311683-56642-2946-0000 by @0.0.0.0:0 W0712 11:53:52.998635 2946 slave.cpp:1133] Ignoring shutdown framework 201307121153-143311683-56642-2946-0000 because it is terminating I0712 11:53:52.999357 2976 detector.cpp:420] Master detector (slave(101)@67.195.138.8:56642) found 0 registered masters I0712 11:53:53.005705 2976 detector.cpp:441] Master detector (slave(101)@67.195.138.8:56642) couldn't find any masters [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2035 ms) I0712 11:53:53.007652 2946 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 33346 [----------] 2 tests from AllocatorZooKeeperTest/0 (4066 ms total) [----------] Global test environment tear-down [==========] 198 tests from 37 test cases ran. (66950 ms total) [ PASSED ] 197 tests. [ FAILED ] 1 test, listed below: [ FAILED ] CoordinatorTest.Truncate 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/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-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
