See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1044/>
------------------------------------------ [...truncated 14294 lines...] I0620 05:25:39.143728 4745 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.143817 4747 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.143995 4747 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 with checkpoint=false I0620 05:25:39.144072 4747 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.144211 4747 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 to [email protected]:40496 I0620 05:25:39.144330 4747 master.cpp:1022] Status update from slave(99)@67.195.138.8:40496: task 0 of framework 201306200525-143311683-40496-4721-0000 is now in state TASK_RUNNING I0620 05:25:39.144419 4747 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.144450 4747 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 0d7fd975-3b34-4b25-914f-7e965a900894) for task 0 of framework 201306200525-143311683-40496-4721-0000 to executor(37)@67.195.138.8:40496 I0620 05:25:39.144654 4747 exec.cpp:290] Executor received status update acknowledgement 0d7fd975-3b34-4b25-914f-7e965a900894 for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.144886 4746 master.cpp:385] Master terminating I0620 05:25:39.145022 4721 master.cpp:207] Shutting down master I0620 05:25:39.145087 4747 status_update_manager.cpp:360] Received status update acknowledgement 0d7fd975-3b34-4b25-914f-7e965a900894 for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.145146 4721 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:39.145196 4747 hierarchical_allocator_process.hpp:412] Deactivated framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.145041 4740 slave.cpp:1885] [email protected]:40496 exited W0620 05:25:39.145469 4740 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0620 05:25:39.145617 4740 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 I0620 05:25:39.145659 4740 slave.cpp:1138] Shutting down framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.145720 4740 slave.cpp:2320] Shutting down executor 'default' of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.145380 4747 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=17983) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.145812 4742 exec.cpp:323] Executor asked to shutdown I0620 05:25:39.145928 4740 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 0d7fd975-3b34-4b25-914f-7e965a900894 for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.146607 4741 master.cpp:228] Master started on 67.195.138.8:40496 I0620 05:25:39.146663 4741 master.cpp:243] Master ID: 201306200525-143311683-40496-4721 I0620 05:25:39.147619 4746 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:40496) found 0 registered masters I0620 05:25:39.153774 4746 detector.cpp:441] Master detector (scheduler(90)@67.195.138.8:40496) couldn't find any masters W0620 05:25:39.153796 4740 master.cpp:83] No whitelist given. Advertising offers for all slaves I0620 05:25:39.148406 4745 detector.cpp:234] Master detector ([email protected]:40496) connected to ZooKeeper ... I0620 05:25:39.147883 4747 detector.cpp:420] Master detector (slave(99)@67.195.138.8:40496) found 0 registered masters I0620 05:25:39.153995 4747 detector.cpp:441] Master detector (slave(99)@67.195.138.8:40496) couldn't find any masters I0620 05:25:39.153946 4745 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0620 05:25:39.153972 4743 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:40496 I0620 05:25:39.153841 4746 sched.cpp:194] No master detected, waiting for another master I0620 05:25:39.154108 4747 slave.cpp:574] Lost master(s) ... waiting I0620 05:25:39.155575 4745 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0620 05:25:39.155993 4743 detector.cpp:420] Master detector (slave(99)@67.195.138.8:40496) found 1 registered masters I0620 05:25:39.156146 4740 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:40496) found 1 registered masters I0620 05:25:39.156306 4745 detector.cpp:420] Master detector ([email protected]:40496) found 1 registered masters I0620 05:25:39.162225 4743 detector.cpp:467] Master detector (slave(99)@67.195.138.8:40496) got new master pid: [email protected]:40496 I0620 05:25:39.162341 4741 slave.cpp:540] New master detected at [email protected]:40496 I0620 05:25:39.162379 4740 detector.cpp:467] Master detector (scheduler(90)@67.195.138.8:40496) got new master pid: [email protected]:40496 I0620 05:25:39.162441 4744 status_update_manager.cpp:155] New master detected at [email protected]:40496 I0620 05:25:39.162538 4745 detector.cpp:467] Master detector ([email protected]:40496) got new master pid: [email protected]:40496 I0620 05:25:39.162591 4741 sched.cpp:177] New master at [email protected]:40496 I0620 05:25:39.162760 4745 master.cpp:526] Elected as master! I0620 05:25:39.162823 4745 master.cpp:604] Re-registering framework 201306200525-143311683-40496-4721-0000 at scheduler(90)@67.195.138.8:40496 I0620 05:25:39.163068 4740 sched.cpp:246] Framework re-registered with 201306200525-143311683-40496-4721-0000 I0620 05:25:39.163141 4741 hierarchical_allocator_process.hpp:327] Added framework 201306200525-143311683-40496-4721-0000 I0620 05:25:39.163199 4741 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0620 05:25:39.163269 4741 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 68.948us I0620 05:25:40.155244 4740 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0620 05:25:40.155292 4740 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 52.649us I0620 05:25:40.163488 4746 master.cpp:963] Attempting to re-register slave 201306200525-143311683-40496-4721-0 at slave(99)@67.195.138.8:40496 (minerva.apache.org) I0620 05:25:40.163543 4746 master.cpp:1851] Adding slave 201306200525-143311683-40496-4721-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 I0620 05:25:40.163630 4745 slave.cpp:636] Re-registered with master [email protected]:40496 I0620 05:25:40.163681 4746 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 W0620 05:25:40.163892 4741 slave.cpp:1274] Ignoring updating pid for framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:40.164029 4746 hierarchical_allocator_process.hpp:449] Added slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 (and cpus=1; mem=524; ports=[31000-32000]; disk=17983 available) I0620 05:25:40.164085 4746 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:40.164221 4746 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306200525-143311683-40496-4721-0 in 142.506us I0620 05:25:40.164284 4745 master.hpp:313] Adding offer 201306200525-143311683-40496-4721-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:40.164381 4745 master.cpp:1239] Sending 1 offers to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:40.164708 4747 sched.cpp:427] Stopping framework '201306200525-143311683-40496-4721-0000' I0620 05:25:40.164731 4743 master.cpp:385] Master terminating I0620 05:25:40.164860 4740 slave.cpp:496] Slave asked to shut down by [email protected]:40496 I0620 05:25:40.164903 4721 master.cpp:207] Shutting down master I0620 05:25:40.164954 4740 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 I0620 05:25:40.165173 4747 hierarchical_allocator_process.hpp:412] Deactivated framework 201306200525-143311683-40496-4721-0000 W0620 05:25:40.165180 4740 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:40.165177 4721 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:40.165338 4740 slave.cpp:1885] [email protected]:40496 exited W0620 05:25:40.169673 4740 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0620 05:25:40.169728 4740 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 W0620 05:25:40.169793 4740 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:40.169821 4747 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 I0620 05:25:40.169913 4721 master.hpp:323] Removing offer 201306200525-143311683-40496-4721-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:40.169996 4747 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=17983 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=17983) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 I0620 05:25:40.170506 4721 slave.cpp:451] Slave terminating I0620 05:25:40.170544 4721 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by @0.0.0.0:0 W0620 05:25:40.170598 4721 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2066 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-06-20 05:25:40,172:4721(0x2b8f20e04700):ZOO_INFO@log_env@679: Client environment:user.name=(null) I0620 05:25:40.171586 4747 master.cpp:228] Master started on 67.195.138.8:40496 I0620 05:25:40.172330 4743 slave.cpp:219] Slave started on 100)@67.195.138.8:40496 I0620 05:25:40.173427 4745 detector.cpp:234] Master detector ([email protected]:40496) connected to ZooKeeper ... I0620 05:25:40.174043 4742 detector.cpp:234] Master detector (slave(100)@67.195.138.8:40496) connected to ZooKeeper ... I0620 05:25:40.174566 4746 detector.cpp:234] Master detector (scheduler(91)@67.195.138.8:40496) connected to ZooKeeper ... I0620 05:25:40.181632 4747 master.cpp:243] Master ID: 201306200525-143311683-40496-4721 I0620 05:25:40.181673 4743 slave.cpp:220] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=17983 W0620 05:25:40.182049 4740 master.cpp:83] No whitelist given. Advertising offers for all slaves I0620 05:25:40.181794 4742 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0620 05:25:40.181733 4745 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0620 05:25:40.182083 4741 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:40496 I0620 05:25:40.181859 4746 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0620 05:25:40.182505 4747 slave.cpp:401] Finished recovery I0620 05:25:40.184020 4745 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0620 05:25:40.184209 4746 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:40496) found 1 registered masters I0620 05:25:40.184592 4742 detector.cpp:420] Master detector (slave(100)@67.195.138.8:40496) found 1 registered masters I0620 05:25:40.184639 4745 detector.cpp:420] Master detector ([email protected]:40496) found 1 registered masters I0620 05:25:40.190253 4746 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:40496) got new master pid: [email protected]:40496 I0620 05:25:40.190389 4746 sched.cpp:177] New master at [email protected]:40496 I0620 05:25:40.190403 4742 detector.cpp:467] Master detector (slave(100)@67.195.138.8:40496) got new master pid: [email protected]:40496 W0620 05:25:40.190500 4747 master.cpp:549] Ignoring register framework message since not elected yet I0620 05:25:40.190594 4745 detector.cpp:467] Master detector ([email protected]:40496) got new master pid: [email protected]:40496 I0620 05:25:40.190623 4744 slave.cpp:540] New master detected at [email protected]:40496 I0620 05:25:40.190758 4742 master.cpp:526] Elected as master! I0620 05:25:40.190793 4741 status_update_manager.cpp:155] New master detected at [email protected]:40496 I0620 05:25:40.190860 4742 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(100)@67.195.138.8:40496 I0620 05:25:40.190951 4742 master.cpp:1851] Adding slave 201306200525-143311683-40496-4721-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 I0620 05:25:40.191037 4747 slave.cpp:600] Registered with master [email protected]:40496; given slave ID 201306200525-143311683-40496-4721-0 I0620 05:25:40.191123 4742 hierarchical_allocator_process.hpp:449] Added slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 (and cpus=2; mem=1024; ports=[31000-32000]; disk=17983 available) I0620 05:25:40.191165 4742 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0620 05:25:40.191234 4742 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306200525-143311683-40496-4721-0 in 67.956us I0620 05:25:41.182682 4747 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0620 05:25:41.182744 4747 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 70.271us I0620 05:25:41.190918 4746 master.cpp:569] Registering framework 201306200525-143311683-40496-4721-0000 at scheduler(91)@67.195.138.8:40496 I0620 05:25:41.191022 4740 sched.cpp:222] Framework registered with 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191090 4743 hierarchical_allocator_process.hpp:327] Added framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191149 4743 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191293 4743 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 151.711us I0620 05:25:41.191315 4742 master.hpp:313] Adding offer 201306200525-143311683-40496-4721-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.191427 4742 master.cpp:1239] Sending 1 offers to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191676 4742 master.cpp:1472] Processing reply for offer 201306200525-143311683-40496-4721-0 on slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) for framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191761 4742 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.191802 4742 master.cpp:1591] Launching task 0 of framework 201306200525-143311683-40496-4721-0000 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) I0620 05:25:41.191912 4743 slave.cpp:740] Got assigned task 0 for framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.191985 4747 hierarchical_allocator_process.hpp:526] Framework 201306200525-143311683-40496-4721-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=17983 unused on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.192101 4747 hierarchical_allocator_process.hpp:569] Framework 201306200525-143311683-40496-4721-0000 filtered slave 201306200525-143311683-40496-4721-0 for 5secs I0620 05:25:41.192160 4743 slave.cpp:838] Launching task 0 for framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.192176 4742 master.hpp:323] Removing offer 201306200525-143311683-40496-4721-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.204128 4743 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_0nrPgq/slaves/201306200525-143311683-40496-4721-0/frameworks/201306200525-143311683-40496-4721-0000/executors/default/runs/83e6a0b7-c498-4288-b299-7ef1a7a0a609' I0620 05:25:41.204287 4743 slave.cpp:949] Queuing task '0' for executor default of framework '201306200525-143311683-40496-4721-0000 I0620 05:25:41.204557 4741 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:40496 with pid 4721 I0620 05:25:41.209682 4743 slave.cpp:522] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_0nrPgq/slaves/201306200525-143311683-40496-4721-0/frameworks/201306200525-143311683-40496-4721-0000/executors/default/runs/83e6a0b7-c498-4288-b299-7ef1a7a0a609' I0620 05:25:41.209821 4743 slave.cpp:1396] Got registration for executor 'default' of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.209913 4743 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.209970 4742 exec.cpp:194] Executor registered on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.210047 4742 exec.cpp:258] Executor asked to run task '0' I0620 05:25:41.211191 4742 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.211266 4742 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.211406 4744 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 with checkpoint=false I0620 05:25:41.211464 4744 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.211607 4744 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 to [email protected]:40496 I0620 05:25:41.211729 4747 master.cpp:1022] Status update from slave(100)@67.195.138.8:40496: task 0 of framework 201306200525-143311683-40496-4721-0000 is now in state TASK_RUNNING I0620 05:25:41.211817 4745 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212018 4745 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 2854f42e-54e0-4954-b561-a9aae85c7f1d) for task 0 of framework 201306200525-143311683-40496-4721-0000 to executor(38)@67.195.138.8:40496 I0620 05:25:41.212224 4744 exec.cpp:290] Executor received status update acknowledgement 2854f42e-54e0-4954-b561-a9aae85c7f1d for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212343 4721 master.cpp:385] Master terminating I0620 05:25:41.212401 4745 status_update_manager.cpp:360] Received status update acknowledgement 2854f42e-54e0-4954-b561-a9aae85c7f1d for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212429 4721 master.cpp:207] Shutting down master I0620 05:25:41.212504 4743 slave.cpp:1885] [email protected]:40496 exited W0620 05:25:41.212615 4743 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0620 05:25:41.212616 4721 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:41.212626 4746 hierarchical_allocator_process.hpp:412] Deactivated framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212700 4743 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 2854f42e-54e0-4954-b561-a9aae85c7f1d for task 0 of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212954 4746 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=17983) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.212995 4743 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 I0620 05:25:41.213119 4743 slave.cpp:1138] Shutting down framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.213174 4743 slave.cpp:2320] Shutting down executor 'default' of framework 201306200525-143311683-40496-4721-0000 I0620 05:25:41.213263 4747 exec.cpp:323] Executor asked to shutdown I0620 05:25:41.213791 4742 master.cpp:228] Master started on 67.195.138.8:40496 I0620 05:25:41.213846 4742 master.cpp:243] Master ID: 201306200525-143311683-40496-4721 I0620 05:25:41.214627 4745 detector.cpp:420] Master detector (slave(100)@67.195.138.8:40496) found 0 registered masters I0620 05:25:41.214804 4747 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:40496) found 0 registered masters W0620 05:25:41.221740 4741 master.cpp:83] No whitelist given. Advertising offers for all slaves I0620 05:25:41.221683 4745 detector.cpp:441] Master detector (slave(100)@67.195.138.8:40496) couldn't find any masters I0620 05:25:41.221914 4741 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:40496 I0620 05:25:41.221747 4747 detector.cpp:441] Master detector (scheduler(91)@67.195.138.8:40496) couldn't find any masters I0620 05:25:41.215494 4744 detector.cpp:234] Master detector ([email protected]:40496) connected to ZooKeeper ... I0620 05:25:41.221945 4745 slave.cpp:574] Lost master(s) ... waiting I0620 05:25:41.222072 4747 sched.cpp:194] No master detected, waiting for another master I0620 05:25:41.222127 4744 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0620 05:25:41.223914 4744 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0620 05:25:41.224252 4747 detector.cpp:420] Master detector (slave(100)@67.195.138.8:40496) found 1 registered masters I0620 05:25:41.224474 4743 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:40496) found 1 registered masters I0620 05:25:41.224617 4744 detector.cpp:420] Master detector ([email protected]:40496) found 1 registered masters I0620 05:25:41.230278 4743 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:40496) got new master pid: [email protected]:40496 I0620 05:25:41.230404 4743 sched.cpp:177] New master at [email protected]:40496 I0620 05:25:41.230450 4747 detector.cpp:467] Master detector (slave(100)@67.195.138.8:40496) got new master pid: [email protected]:40496 I0620 05:25:41.230594 4747 slave.cpp:540] New master detected at [email protected]:40496 I0620 05:25:41.230671 4743 status_update_manager.cpp:155] New master detected at [email protected]:40496 I0620 05:25:41.230782 4744 detector.cpp:467] Master detector ([email protected]:40496) got new master pid: [email protected]:40496 W0620 05:25:41.230831 4741 master.cpp:918] Ignoring re-register slave message from minerva.apache.org since not elected yet I0620 05:25:41.230940 4741 master.cpp:526] Elected as master! I0620 05:25:42.222079 4744 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0620 05:25:42.222139 4744 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 61.743us I0620 05:25:42.231358 4744 master.cpp:963] Attempting to re-register slave 201306200525-143311683-40496-4721-0 at slave(100)@67.195.138.8:40496 (minerva.apache.org) I0620 05:25:42.231407 4744 master.cpp:1851] Adding slave 201306200525-143311683-40496-4721-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 I0620 05:25:42.231526 4741 slave.cpp:636] Re-registered with master [email protected]:40496 I0620 05:25:42.231557 4744 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 W0620 05:25:42.231688 4744 master.cpp:1943] Possibly orphaned task 0 of framework 201306200525-143311683-40496-4721-0000 running on slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) I0620 05:25:42.231982 4743 hierarchical_allocator_process.hpp:449] Added slave 201306200525-143311683-40496-4721-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=17983 (and cpus=1; mem=524; ports=[31000-32000]; disk=17983 available) I0620 05:25:42.232069 4743 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0620 05:25:42.232116 4743 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306200525-143311683-40496-4721-0 in 47.797us I0620 05:25:43.191249 4740 master.cpp:604] Re-registering framework 201306200525-143311683-40496-4721-0000 at scheduler(91)@67.195.138.8:40496 I0620 05:25:43.191418 4745 sched.cpp:246] Framework re-registered with 201306200525-143311683-40496-4721-0000 W0620 05:25:43.191464 4740 slave.cpp:1274] Ignoring updating pid for framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:43.191524 4741 hierarchical_allocator_process.hpp:327] Added framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.197706 4741 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.197849 4741 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 148.617us I0620 05:25:43.197896 4744 master.hpp:313] Adding offer 201306200525-143311683-40496-4721-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:43.197962 4744 master.cpp:1239] Sending 1 offers to framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.198271 4745 master.cpp:385] Master terminating I0620 05:25:43.198271 4743 sched.cpp:427] Stopping framework '201306200525-143311683-40496-4721-0000' I0620 05:25:43.198375 4721 master.cpp:207] Shutting down master I0620 05:25:43.198336 4741 slave.cpp:496] Slave asked to shut down by [email protected]:40496 I0620 05:25:43.198583 4745 hierarchical_allocator_process.hpp:412] Deactivated framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.198667 4741 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 I0620 05:25:43.198608 4721 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306200525-143311683-40496-4721-0 W0620 05:25:43.198748 4741 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:43.198856 4741 slave.cpp:1885] [email protected]:40496 exited I0620 05:25:43.198950 4745 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.198977 4721 master.hpp:323] Removing offer 201306200525-143311683-40496-4721-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=17983 on slave 201306200525-143311683-40496-4721-0 I0620 05:25:43.205811 4745 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=17983 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=17983) on slave 201306200525-143311683-40496-4721-0 from framework 201306200525-143311683-40496-4721-0000 W0620 05:25:43.205617 4741 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0620 05:25:43.205972 4745 hierarchical_allocator_process.hpp:367] Removed framework 201306200525-143311683-40496-4721-0000 I0620 05:25:43.206153 4745 hierarchical_allocator_process.hpp:477] Removed slave 201306200525-143311683-40496-4721-0 I0620 05:25:43.206043 4741 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by [email protected]:40496 W0620 05:25:43.206316 4741 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:43.206390 4741 slave.cpp:451] Slave terminating I0620 05:25:43.206470 4741 slave.cpp:1113] Asked to shut down framework 201306200525-143311683-40496-4721-0000 by @0.0.0.0:0 I0620 05:25:43.207250 4742 detector.cpp:420] Master detector (slave(100)@67.195.138.8:40496) found 0 registered masters W0620 05:25:43.213654 4741 slave.cpp:1134] Ignoring shutdown framework 201306200525-143311683-40496-4721-0000 because it is terminating I0620 05:25:43.213726 4742 detector.cpp:441] Master detector (slave(100)@67.195.138.8:40496) couldn't find any masters [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (3043 ms) I0620 05:25:43.215692 4721 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 44625 [----------] 2 tests from AllocatorZooKeeperTest/0 (5109 ms total) [----------] Global test environment tear-down [==========] 194 tests from 37 test cases ran. (69157 ms total) [ PASSED ] 193 tests. [ FAILED ] 1 test, listed below: [ FAILED ] FaultToleranceTest.ReconcileIncompleteTasks 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
