You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/07/06 15:50:32 UTC

Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1115

See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1115/>

------------------------------------------
[...truncated 14726 lines...]
W0706 13:50:10.063324  3701 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0706 13:50:10.269690  3701 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
I0706 13:50:10.377895  3701 slave.cpp:1137] Shutting down framework 201307061350-160088899-49663-3668-0000
I0706 13:50:10.163700  3705 detector.cpp:420] Master detector (scheduler(93)@67.195.138.9:49663)  found 0 registered masters
I0706 13:50:10.592892  3705 detector.cpp:441] Master detector (scheduler(93)@67.195.138.9:49663) couldn't find any masters
I0706 13:50:10.124104  3706 master.cpp:230] Master started on 67.195.138.9:49663
I0706 13:50:10.163461  3699 detector.cpp:234] Master detector (master@67.195.138.9:49663) connected to ZooKeeper ...
I0706 13:50:10.640652  3699 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0706 13:50:10.163868  3702 detector.cpp:420] Master detector (slave(100)@67.195.138.9:49663)  found 0 registered masters
I0706 13:50:10.624814  3705 sched.cpp:194] No master detected, waiting for another master
I0706 13:50:10.632697  3706 master.cpp:245] Master ID: 201307061350-160088899-49663-3668
I0706 13:50:10.485390  3701 slave.cpp:2328] Shutting down executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:10.684450  3702 detector.cpp:441] Master detector (slave(100)@67.195.138.9:49663) couldn't find any masters
I0706 13:50:10.689059  3699 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0706 13:50:10.689628  3703 detector.cpp:420] Master detector (scheduler(93)@67.195.138.9:49663)  found 1 registered masters
W0706 13:50:10.808050  3700 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0706 13:50:10.808271  3704 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:49663
I0706 13:50:10.915460  3706 exec.cpp:323] Executor asked to shutdown
I0706 13:50:10.915489  3701 slave.cpp:1343] Status update manager successfully handled status update acknowledgement ce2a8a0d-8266-4d1a-b206-6ced9fe64054 for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:11.023190  3702 detector.cpp:420] Master detector (slave(100)@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:11.138911  3699 detector.cpp:420] Master detector (master@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:11.242449  3703 detector.cpp:467] Master detector (scheduler(93)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:11.715813  3701 slave.cpp:563] Lost master(s) ... waiting
I0706 13:50:11.907457  3702 detector.cpp:467] Master detector (slave(100)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:11.907588  3699 detector.cpp:467] Master detector (master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:12.058480  3703 sched.cpp:177] New master at master@67.195.138.9:49663
I0706 13:50:12.285539  3702 slave.cpp:529] New master detected at master@67.195.138.9:49663
I0706 13:50:12.404644  3699 master.cpp:583] Elected as master!
I0706 13:50:12.469133  3705 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0706 13:50:12.632155  3700 status_update_manager.cpp:155] New master detected at master@67.195.138.9:49663
I0706 13:50:12.747648  3699 master.cpp:675] Re-registering framework 201307061350-160088899-49663-3668-0000 at scheduler(93)@67.195.138.9:49663
I0706 13:50:12.858504  3705 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 389.378461ms
I0706 13:50:13.090353  3699 sched.cpp:246] Framework re-registered with 201307061350-160088899-49663-3668-0000
I0706 13:50:13.200966  3705 hierarchical_allocator_process.hpp:335] Added framework 201307061350-160088899-49663-3668-0000
I0706 13:50:13.304154  3700 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201307061350-160088899-49663-3668-0000'
I0706 13:50:13.423920  3705 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0706 13:50:13.638923  3705 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 215.010062ms
I0706 13:50:13.632593  3700 master.cpp:1034] Attempting to re-register slave 201307061350-160088899-49663-3668-0 at slave(100)@67.195.138.9:49663 (vesta.apache.org)
I0706 13:50:13.861912  3700 master.cpp:1929] Adding slave 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=775877
I0706 13:50:13.981433  3700 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:13.981462  3704 slave.cpp:630] Re-registered with master master@67.195.138.9:49663
I0706 13:50:14.093021  3700 hierarchical_allocator_process.hpp:448] Added slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=775877 (and cpus=1; mem=524; ports=[31000-32000]; disk=775877 available)
W0706 13:50:14.205776  3704 slave.cpp:1273] Ignoring updating pid for framework 201307061350-160088899-49663-3668-0000 because it is terminating
I0706 13:50:14.317327  3700 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0 to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:14.550833  3700 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307061350-160088899-49663-3668-0 in 233.517009ms
I0706 13:50:14.550885  3706 master.hpp:327] Adding offer 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:14.667939  3700 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 9.422us
I0706 13:50:14.785784  3706 master.cpp:1310] Sending 1 offers to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:15.006773  3706 sched.cpp:427] Stopping framework '201307061350-160088899-49663-3668-0000'
I0706 13:50:15.006790  3668 master.cpp:436] Master terminating
I0706 13:50:15.235687  3700 slave.cpp:485] Slave asked to shut down by master@67.195.138.9:49663
I0706 13:50:15.235709  3668 master.cpp:209] Shutting down master
I0706 13:50:15.353168  3700 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
I0706 13:50:15.462653  3668 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:15.462690  3699 hierarchical_allocator_process.hpp:411] Deactivated framework 201307061350-160088899-49663-3668-0000
W0706 13:50:15.578080  3700 slave.cpp:1133] Ignoring shutdown framework 201307061350-160088899-49663-3668-0000 because it is terminating
I0706 13:50:15.687880  3668 master.hpp:337] Removing offer 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:15.797114  3699 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307061350-160088899-49663-3668-0 from framework 201307061350-160088899-49663-3668-0000
I0706 13:50:15.908555  3700 slave.cpp:1884] master@67.195.138.9:49663 exited
I0706 13:50:16.036258  3705 detector.cpp:420] Master detector (slave(100)@67.195.138.9:49663)  found 0 registered masters
I0706 13:50:16.151563  3699 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=775877 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=775877) on slave 201307061350-160088899-49663-3668-0 from framework 201307061350-160088899-49663-3668-0000
W0706 13:50:16.266923  3700 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0706 13:50:16.374421  3705 detector.cpp:441] Master detector (slave(100)@67.195.138.9:49663) couldn't find any masters
I0706 13:50:16.484621  3699 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 6.559us
I0706 13:50:16.605346  3700 slave.cpp:440] Slave terminating
I0706 13:50:17.071214  3700 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by @0.0.0.0:0
W0706 13:50:17.071259  3700 slave.cpp:1133] Ignoring shutdown framework 201307061350-160088899-49663-3668-0000 because it is terminating
[       OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (15633 ms)
[ RUN      ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
2013-07-06 13:50:17,224:3668(0x2b401b9f3700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@679: Client environment:user.name=(null)
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@699: Client environment:user.dir=/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:57500 sessionTimeout=10000 watcher=0x2b40192fdcf0 sessionId=0 sessionPasswd=<null> context=0x2b4028007ee0 flags=0
2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_DEBUG@start_threads@152: starting threads...
2013-07-06 13:50:17,225:3668(0x2b4131176700):ZOO_DEBUG@do_io@279: started IO thread
2013-07-06 13:50:17,225:3668(0x2b4130972700):ZOO_DEBUG@do_completion@326: started completion thread
2013-07-06 13:50:17,225:3668(0x2b4131176700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:57500]
I0706 13:50:17.080109  3701 master.cpp:230] Master started on 67.195.138.9:49663
I0706 13:50:17.224833  3705 slave.cpp:113] Slave started on 101)@67.195.138.9:49663
I0706 13:50:17.258502  3704 detector.cpp:234] Master detector (master@67.195.138.9:49663) connected to ZooKeeper ...
I0706 13:50:17.258944  3702 detector.cpp:234] Master detector (slave(101)@67.195.138.9:49663) connected to ZooKeeper ...
I0706 13:50:17.259333  3706 detector.cpp:234] Master detector (scheduler(94)@67.195.138.9:49663) connected to ZooKeeper ...
I0706 13:50:17.476649  3701 master.cpp:245] Master ID: 201307061350-160088899-49663-3668
I0706 13:50:17.592967  3705 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=775877
I0706 13:50:17.704339  3704 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0706 13:50:17.815253  3702 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0706 13:50:17.931290  3706 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
W0706 13:50:18.038905  3703 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0706 13:50:18.038941  3699 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:49663
I0706 13:50:18.150843  3701 slave.cpp:390] Finished recovery
I0706 13:50:18.265494  3704 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0706 13:50:18.366114  3702 detector.cpp:420] Master detector (slave(101)@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:18.477798  3706 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:18.935255  3704 detector.cpp:420] Master detector (master@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:19.054788  3702 detector.cpp:467] Master detector (slave(101)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:19.166283  3706 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:19.277853  3704 detector.cpp:467] Master detector (master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:19.388984  3702 slave.cpp:529] New master detected at master@67.195.138.9:49663
I0706 13:50:19.504180  3706 sched.cpp:177] New master at master@67.195.138.9:49663
I0706 13:50:19.616061  3704 master.cpp:583] Elected as master!
I0706 13:50:19.954679  3704 master.cpp:962] Attempting to register slave on vesta.apache.org at slave(101)@67.195.138.9:49663
I0706 13:50:19.731586  3702 status_update_manager.cpp:155] New master detected at master@67.195.138.9:49663
I0706 13:50:19.712584  3703 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0706 13:50:20.062224  3704 master.cpp:1929] Adding slave 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=775877
I0706 13:50:20.284523  3703 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 571.939031ms
I0706 13:50:20.392953  3699 slave.cpp:589] Registered with master master@67.195.138.9:49663; given slave ID 201307061350-160088899-49663-3668-0
I0706 13:50:20.393054  3704 master.cpp:633] Registering framework 201307061350-160088899-49663-3668-0000 at scheduler(94)@67.195.138.9:49663
I0706 13:50:20.499585  3703 hierarchical_allocator_process.hpp:448] Added slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=775877 (and cpus=2; mem=1024; ports=[31000-32000]; disk=775877 available)
I0706 13:50:20.731552  3704 sched.cpp:222] Framework registered with 201307061350-160088899-49663-3668-0000
I0706 13:50:20.839154  3703 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307061350-160088899-49663-3668-0 in 7.476us
I0706 13:50:21.060978  3703 hierarchical_allocator_process.hpp:335] Added framework 201307061350-160088899-49663-3668-0000
I0706 13:50:21.173745  3703 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0 to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:21.284070  3703 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 110.341082ms
I0706 13:50:21.284129  3700 master.hpp:327] Adding offer 201307061350-160088899-49663-3668-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:21.508411  3700 master.cpp:1310] Sending 1 offers to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:21.500586  3699 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 18.516us
I0706 13:50:21.618593  3700 master.cpp:1543] Processing reply for offer 201307061350-160088899-49663-3668-0 on slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:21.845422  3700 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:22.058199  3700 master.cpp:1662] Launching task 0 of framework 201307061350-160088899-49663-3668-0000 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0 (vesta.apache.org)
I0706 13:50:22.058406  3700 master.hpp:337] Removing offer 201307061350-160088899-49663-3668-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:22.058549  3701 slave.cpp:739] Got assigned task 0 for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:22.058574  3705 hierarchical_allocator_process.hpp:525] Framework 201307061350-160088899-49663-3668-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=775877 unused on slave 201307061350-160088899-49663-3668-0
I0706 13:50:22.321369  3701 slave.cpp:837] Launching task 0 for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:22.436779  3705 hierarchical_allocator_process.hpp:568] Framework 201307061350-160088899-49663-3668-0000 filtered slave 201307061350-160088899-49663-3668-0 for 5secs
I0706 13:50:22.547595  3701 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738'
I0706 13:50:22.731631  3699 hierarchical_allocator_process.hpp:796] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0 for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:22.775498  3701 slave.cpp:948] Queuing task '0' for executor default of framework '201307061350-160088899-49663-3668-0000
I0706 13:50:22.775655  3704 exec.cpp:170] Executor started at: executor(38)@67.195.138.9:49663 with pid 3668
I0706 13:50:22.886931  3699 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 155.323706ms
I0706 13:50:23.000134  3701 slave.cpp:511] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738'
I0706 13:50:23.338601  3701 slave.cpp:1395] Got registration for executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:23.450140  3701 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:23.450177  3706 exec.cpp:194] Executor registered on slave 201307061350-160088899-49663-3668-0
W0706 13:50:23.588397  3700 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0706 13:50:23.673056  3706 exec.cpp:258] Executor asked to run task '0'
I0706 13:50:23.909185  3706 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:24.019520  3705 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000 from executor(38)@67.195.138.9:49663
I0706 13:50:24.129947  3705 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000 with checkpoint=false
I0706 13:50:24.245395  3705 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:24.357918  3705 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000 to master@67.195.138.9:49663
I0706 13:50:24.224131  3706 hierarchical_allocator_process.hpp:796] Filtered cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0 for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:24.469468  3702 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:24.469478  3703 master.cpp:1093] Status update from slave(101)@67.195.138.9:49663: task 0 of framework 201307061350-160088899-49663-3668-0000 is now in state TASK_RUNNING
I0706 13:50:24.584825  3706 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 360.758594ms
I0706 13:50:24.696347  3702 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework 201307061350-160088899-49663-3668-0000 to executor(38)@67.195.138.9:49663
I0706 13:50:24.820129  3668 master.cpp:436] Master terminating
I0706 13:50:25.158082  3668 master.cpp:209] Shutting down master
I0706 13:50:25.042801  3702 status_update_manager.cpp:360] Received status update acknowledgement c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:25.042719  3699 exec.cpp:290] Executor received status update acknowledgement c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:25.158136  3700 slave.cpp:1884] master@67.195.138.9:49663 exited
W0706 13:50:25.604001  3700 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0706 13:50:25.269656  3668 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:25.269635  3705 hierarchical_allocator_process.hpp:411] Deactivated framework 201307061350-160088899-49663-3668-0000
I0706 13:50:25.731475  3700 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
I0706 13:50:26.169428  3700 slave.cpp:1137] Shutting down framework 201307061350-160088899-49663-3668-0000
I0706 13:50:26.280905  3700 slave.cpp:2328] Shutting down executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:26.055472  3703 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:49663)  found 0 registered masters
I0706 13:50:26.055610  3704 detector.cpp:420] Master detector (slave(101)@67.195.138.9:49663)  found 0 registered masters
I0706 13:50:26.619339  3704 detector.cpp:441] Master detector (slave(101)@67.195.138.9:49663) couldn't find any masters
I0706 13:50:26.055270  3706 detector.cpp:234] Master detector (master@67.195.138.9:49663) connected to ZooKeeper ...
I0706 13:50:26.874203  3706 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0706 13:50:26.392519  3701 exec.cpp:323] Executor asked to shutdown
I0706 13:50:26.503885  3703 detector.cpp:441] Master detector (scheduler(94)@67.195.138.9:49663) couldn't find any masters
I0706 13:50:26.014909  3702 master.cpp:230] Master started on 67.195.138.9:49663
I0706 13:50:26.392448  3700 slave.cpp:1343] Status update manager successfully handled status update acknowledgement c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:27.037516  3706 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0706 13:50:27.038017  3699 detector.cpp:420] Master detector (slave(101)@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:27.141072  3704 sched.cpp:194] No master detected, waiting for another master
I0706 13:50:27.141629  3703 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:27.248498  3702 master.cpp:245] Master ID: 201307061350-160088899-49663-3668
I0706 13:50:27.363991  3700 slave.cpp:563] Lost master(s) ... waiting
I0706 13:50:27.471997  3706 detector.cpp:420] Master detector (master@67.195.138.9:49663)  found 1 registered masters
I0706 13:50:27.583493  3699 detector.cpp:467] Master detector (slave(101)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:27.802623  3703 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
W0706 13:50:27.909575  3704 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0706 13:50:27.909826  3705 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:49663
I0706 13:50:28.136857  3706 detector.cpp:467] Master detector (master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
I0706 13:50:28.251948  3699 slave.cpp:529] New master detected at master@67.195.138.9:49663
I0706 13:50:28.339752  3702 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201307061350-160088899-49663-3668-0000'
I0706 13:50:28.363417  3703 sched.cpp:177] New master at master@67.195.138.9:49663
I0706 13:50:28.714254  3706 master.cpp:583] Elected as master!
I0706 13:50:28.829726  3701 status_update_manager.cpp:155] New master detected at master@67.195.138.9:49663
I0706 13:50:29.160222  3706 master.cpp:1034] Attempting to re-register slave 201307061350-160088899-49663-3668-0 at slave(101)@67.195.138.9:49663 (vesta.apache.org)
I0706 13:50:29.387094  3706 master.cpp:1929] Adding slave 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=775877
I0706 13:50:29.494673  3702 slave.cpp:630] Re-registered with master master@67.195.138.9:49663
I0706 13:50:29.494670  3706 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
W0706 13:50:29.723273  3706 master.cpp:2021] Possibly orphaned task 0 of framework 201307061350-160088899-49663-3668-0000 running on slave 201307061350-160088899-49663-3668-0 (vesta.apache.org)
I0706 13:50:29.602946  3704 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0706 13:50:29.960464  3704 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 357.519618ms
I0706 13:50:30.051612  3701 master.cpp:675] Re-registering framework 201307061350-160088899-49663-3668-0000 at scheduler(94)@67.195.138.9:49663
I0706 13:50:30.073490  3704 hierarchical_allocator_process.hpp:448] Added slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=775877 (and cpus=1; mem=524; ports=[31000-32000]; disk=775877 available)
I0706 13:50:30.180987  3700 sched.cpp:246] Framework re-registered with 201307061350-160088899-49663-3668-0000
W0706 13:50:30.181030  3701 slave.cpp:1273] Ignoring updating pid for framework 201307061350-160088899-49663-3668-0000 because it is terminating
I0706 13:50:30.292280  3704 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307061350-160088899-49663-3668-0 in 5.705us
I0706 13:50:30.638453  3704 hierarchical_allocator_process.hpp:335] Added framework 201307061350-160088899-49663-3668-0000
I0706 13:50:30.749846  3704 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0 to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:30.861340  3704 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 111.513896ms
I0706 13:50:30.861379  3705 master.hpp:327] Adding offer 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:31.080117  3705 master.cpp:1310] Sending 1 offers to framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.073792  3706 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 13.353us
I0706 13:50:31.199796  3704 sched.cpp:427] Stopping framework '201307061350-160088899-49663-3668-0000'
I0706 13:50:31.199822  3703 master.cpp:436] Master terminating
I0706 13:50:31.393146  3702 slave.cpp:2397] Killing executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.545691  3668 master.cpp:209] Shutting down master
I0706 13:50:31.816084  3701 hierarchical_allocator_process.hpp:411] Deactivated framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.816104  3668 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:31.816349  3668 master.hpp:337] Removing offer 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave 201307061350-160088899-49663-3668-0
I0706 13:50:31.816362  3705 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307061350-160088899-49663-3668-0 from framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.816519  3705 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=775877 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=775877) on slave 201307061350-160088899-49663-3668-0 from framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.816632  3705 hierarchical_allocator_process.hpp:366] Removed framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.816680  3705 hierarchical_allocator_process.hpp:476] Removed slave 201307061350-160088899-49663-3668-0
I0706 13:50:31.815983  3702 slave.cpp:485] Slave asked to shut down by master@67.195.138.9:49663
I0706 13:50:31.816854  3702 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
W0706 13:50:31.816872  3702 slave.cpp:1133] Ignoring shutdown framework 201307061350-160088899-49663-3668-0000 because it is terminating
I0706 13:50:31.816896  3702 slave.cpp:1884] master@67.195.138.9:49663 exited
W0706 13:50:31.816910  3702 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0706 13:50:31.816931  3702 slave.cpp:2060] Executor 'default' of framework 201307061350-160088899-49663-3668-0000 has exited with status 0
I0706 13:50:31.817004  3702 slave.cpp:2195] Cleaning up executor 'default' of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.817122  3705 gc.cpp:56] Scheduling '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738' for removal
I0706 13:50:31.817250  3702 slave.cpp:2259] Cleaning up framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.817265  3705 gc.cpp:56] Scheduling '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default' for removal
I0706 13:50:31.817380  3701 status_update_manager.cpp:252] Closing status update streams for framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.817420  3701 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201307061350-160088899-49663-3668-0000
I0706 13:50:31.817431  3702 slave.cpp:440] Slave terminating
I0706 13:50:31.817450  3706 gc.cpp:56] Scheduling '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000' for removal
[       OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (14739 ms)
I0706 13:50:31.875761  3668 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 57500
[----------] 2 tests from AllocatorZooKeeperTest/0 (30373 ms total)

[----------] Global test environment tear-down
[==========] 198 tests from 37 test cases ran. (201559 ms total)
[  PASSED  ] 197 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] AllocatorTest/0.SchedulerFailover, where TypeParam = mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter>

 1 FAILED TEST
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1116

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1116/>


Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1115

Posted by Benjamin Mahler <be...@gmail.com>.
Reopened: https://issues.apache.org/jira/browse/MESOS-410


On Sat, Jul 6, 2013 at 6:50 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1115/
> >
>
> ------------------------------------------
> [...truncated 14726 lines...]
> W0706 13:50:10.063324  3701 slave.cpp:1887] Master disconnected! Waiting
> for a new master to be elected
> I0706 13:50:10.269690  3701 slave.cpp:1112] Asked to shut down framework
> 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
> I0706 13:50:10.377895  3701 slave.cpp:1137] Shutting down framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:10.163700  3705 detector.cpp:420] Master detector
> (scheduler(93)@67.195.138.9:49663)  found 0 registered masters
> I0706 13:50:10.592892  3705 detector.cpp:441] Master detector
> (scheduler(93)@67.195.138.9:49663) couldn't find any masters
> I0706 13:50:10.124104  3706 master.cpp:230] Master started on
> 67.195.138.9:49663
> I0706 13:50:10.163461  3699 detector.cpp:234] Master detector (
> master@67.195.138.9:49663) connected to ZooKeeper ...
> I0706 13:50:10.640652  3699 detector.cpp:251] Trying to create path
> '/znode' in ZooKeeper
> I0706 13:50:10.163868  3702 detector.cpp:420] Master detector (slave(100)@
> 67.195.138.9:49663)  found 0 registered masters
> I0706 13:50:10.624814  3705 sched.cpp:194] No master detected, waiting for
> another master
> I0706 13:50:10.632697  3706 master.cpp:245] Master ID:
> 201307061350-160088899-49663-3668
> I0706 13:50:10.485390  3701 slave.cpp:2328] Shutting down executor
> 'default' of framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:10.684450  3702 detector.cpp:441] Master detector (slave(100)@
> 67.195.138.9:49663) couldn't find any masters
> I0706 13:50:10.689059  3699 detector.cpp:281] Created ephemeral/sequence
> znode at '/znode/0000000002'
> I0706 13:50:10.689628  3703 detector.cpp:420] Master detector
> (scheduler(93)@67.195.138.9:49663)  found 1 registered masters
> W0706 13:50:10.808050  3700 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0706 13:50:10.808271  3704 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@67.195.138.9:49663
> I0706 13:50:10.915460  3706 exec.cpp:323] Executor asked to shutdown
> I0706 13:50:10.915489  3701 slave.cpp:1343] Status update manager
> successfully handled status update acknowledgement
> ce2a8a0d-8266-4d1a-b206-6ced9fe64054 for task 0 of framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:11.023190  3702 detector.cpp:420] Master detector (slave(100)@
> 67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:11.138911  3699 detector.cpp:420] Master detector (
> master@67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:11.242449 3703 detector.cpp:467] Master detector
> (scheduler(93)@67.195.138.9:49663)  got new master pid:
> master@67.195.138.9:49663
> I0706 13:50:11.715813 3701 slave.cpp:563] Lost master(s) ... waiting
> I0706 13:50:11.907457 3702 detector.cpp:467] Master detector (slave(100)@
> 67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:11.907588 3699 detector.cpp:467] Master detector (
> master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:12.058480  3703 sched.cpp:177] New master at
> master@67.195.138.9:49663
> I0706 13:50:12.285539  3702 slave.cpp:529] New master detected at
> master@67.195.138.9:49663
> I0706 13:50:12.404644  3699 master.cpp:583] Elected as master!
> I0706 13:50:12.469133  3705 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0706 13:50:12.632155  3700 status_update_manager.cpp:155] New master
> detected at master@67.195.138.9:49663
> I0706 13:50:12.747648  3699 master.cpp:675] Re-registering framework
> 201307061350-160088899-49663-3668-0000 at scheduler(93)@67.195.138.9:49663
> I0706 13:50:12.858504  3705 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 389.378461ms
> I0706 13:50:13.090353  3699 sched.cpp:246] Framework re-registered with
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:13.200966  3705 hierarchical_allocator_process.hpp:335] Added
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:13.304154  3700 monitor.cpp:180] Publishing resource usage for
> executor 'default' of framework '201307061350-160088899-49663-3668-0000'
> I0706 13:50:13.423920  3705 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0706 13:50:13.638923  3705 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 215.010062ms
> I0706 13:50:13.632593  3700 master.cpp:1034] Attempting to re-register
> slave 201307061350-160088899-49663-3668-0 at slave(100)@67.195.138.9:49663(
> vesta.apache.org)
> I0706 13:50:13.861912  3700 master.cpp:1929] Adding slave
> 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877
> I0706 13:50:13.981433  3700 master.hpp:305] Adding task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> I0706 13:50:13.981462  3704 slave.cpp:630] Re-registered with master
> master@67.195.138.9:49663
> I0706 13:50:14.093021  3700 hierarchical_allocator_process.hpp:448] Added
> slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877 (and cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 available)
> W0706 13:50:14.205776  3704 slave.cpp:1273] Ignoring updating pid for
> framework 201307061350-160088899-49663-3668-0000 because it is terminating
> I0706 13:50:14.317327  3700 hierarchical_allocator_process.hpp:726]
> Offering cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0 to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:14.550833  3700 hierarchical_allocator_process.hpp:686]
> Performed allocation for slave 201307061350-160088899-49663-3668-0 in
> 233.517009ms
> I0706 13:50:14.550885  3706 master.hpp:327] Adding offer
> 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:14.667939  3700 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 9.422us
> I0706 13:50:14.785784  3706 master.cpp:1310] Sending 1 offers to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:15.006773  3706 sched.cpp:427] Stopping framework
> '201307061350-160088899-49663-3668-0000'
> I0706 13:50:15.006790  3668 master.cpp:436] Master terminating
> I0706 13:50:15.235687  3700 slave.cpp:485] Slave asked to shut down by
> master@67.195.138.9:49663
> I0706 13:50:15.235709  3668 master.cpp:209] Shutting down master
> I0706 13:50:15.353168  3700 slave.cpp:1112] Asked to shut down framework
> 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
> I0706 13:50:15.462653  3668 master.hpp:317] Removing task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> I0706 13:50:15.462690  3699 hierarchical_allocator_process.hpp:411]
> Deactivated framework 201307061350-160088899-49663-3668-0000
> W0706 13:50:15.578080  3700 slave.cpp:1133] Ignoring shutdown framework
> 201307061350-160088899-49663-3668-0000 because it is terminating
> I0706 13:50:15.687880  3668 master.hpp:337] Removing offer
> 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:15.797114  3699 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[];
> disk=0) on slave 201307061350-160088899-49663-3668-0 from framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:15.908555  3700 slave.cpp:1884] master@67.195.138.9:49663exited
> I0706 13:50:16.036258  3705 detector.cpp:420] Master detector (slave(100)@
> 67.195.138.9:49663)  found 0 registered masters
> I0706 13:50:16.151563  3699 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=524; ports=[31000-32000]; disk=775877 (total
> allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=775877) on slave
> 201307061350-160088899-49663-3668-0 from framework
> 201307061350-160088899-49663-3668-0000
> W0706 13:50:16.266923  3700 slave.cpp:1887] Master disconnected! Waiting
> for a new master to be elected
> I0706 13:50:16.374421  3705 detector.cpp:441] Master detector (slave(100)@
> 67.195.138.9:49663) couldn't find any masters
> I0706 13:50:16.484621  3699 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 6.559us
> I0706 13:50:16.605346  3700 slave.cpp:440] Slave terminating
> I0706 13:50:17.071214  3700 slave.cpp:1112] Asked to shut down framework
> 201307061350-160088899-49663-3668-0000 by @0.0.0.0:0
> W0706 13:50:17.071259  3700 slave.cpp:1133] Ignoring shutdown framework
> 201307061350-160088899-49663-3668-0000 because it is terminating
> [       OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (15633 ms)
> [ RUN      ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
> 2013-07-06 13:50:17,224:3668(0x2b401b9f3700):ZOO_INFO@log_env@658: Client
> environment:zookeeper.version=zookeeper C client 3.3.4
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@662: Client
> environment:host.name=vesta.apache.org
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@669: Client
> environment:os.name=Linux
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@670: Client
> environment:os.arch=3.2.0-38-generic
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@671: Client
> environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@679: Client
> environment:user.name=(null)
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@687: Client
> environment:user.home=/home/jenkins
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@log_env@699: Client
> environment:user.dir=/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_INFO@zookeeper_init@727:
> Initiating client connection, host=127.0.0.1:57500 sessionTimeout=10000
> watcher=0x2b40192fdcf0 sessionId=0 sessionPasswd=<null>
> context=0x2b4028007ee0 flags=0
> 2013-07-06 13:50:17,225:3668(0x2b401b9f3700):ZOO_DEBUG@start_threads@152:
> starting threads...
> 2013-07-06 13:50:17,225:3668(0x2b4131176700):ZOO_DEBUG@do_io@279: started
> IO thread
> 2013-07-06 13:50:17,225:3668(0x2b4130972700):ZOO_DEBUG@do_completion@326:
> started completion thread
> 2013-07-06 13:50:17,225:3668(0x2b4131176700):ZOO_INFO@check_events@1585:
> initiated connection to server [127.0.0.1:57500]
> I0706 13:50:17.080109  3701 master.cpp:230] Master started on
> 67.195.138.9:49663
> I0706 13:50:17.224833  3705 slave.cpp:113] Slave started on 101)@
> 67.195.138.9:49663
> I0706 13:50:17.258502  3704 detector.cpp:234] Master detector (
> master@67.195.138.9:49663) connected to ZooKeeper ...
> I0706 13:50:17.258944  3702 detector.cpp:234] Master detector (slave(101)@
> 67.195.138.9:49663) connected to ZooKeeper ...
> I0706 13:50:17.259333  3706 detector.cpp:234] Master detector
> (scheduler(94)@67.195.138.9:49663) connected to ZooKeeper ...
> I0706 13:50:17.476649  3701 master.cpp:245] Master ID:
> 201307061350-160088899-49663-3668
> I0706 13:50:17.592967  3705 slave.cpp:205] Slave resources: cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877
> I0706 13:50:17.704339  3704 detector.cpp:251] Trying to create path
> '/znode' in ZooKeeper
> I0706 13:50:17.815253  3702 detector.cpp:251] Trying to create path
> '/znode' in ZooKeeper
> I0706 13:50:17.931290  3706 detector.cpp:251] Trying to create path
> '/znode' in ZooKeeper
> W0706 13:50:18.038905  3703 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0706 13:50:18.038941  3699 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@67.195.138.9:49663
> I0706 13:50:18.150843  3701 slave.cpp:390] Finished recovery
> I0706 13:50:18.265494  3704 detector.cpp:281] Created ephemeral/sequence
> znode at '/znode/0000000004'
> I0706 13:50:18.366114  3702 detector.cpp:420] Master detector (slave(101)@
> 67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:18.477798  3706 detector.cpp:420] Master detector
> (scheduler(94)@67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:18.935255  3704 detector.cpp:420] Master detector (
> master@67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:19.054788  3702 detector.cpp:467] Master detector (slave(101)@
> 67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:19.166283  3706 detector.cpp:467] Master detector
> (scheduler(94)@67.195.138.9:49663)  got new master pid:
> master@67.195.138.9:49663
> I0706 13:50:19.277853  3704 detector.cpp:467] Master detector (
> master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:19.388984  3702 slave.cpp:529] New master detected at
> master@67.195.138.9:49663
> I0706 13:50:19.504180  3706 sched.cpp:177] New master at
> master@67.195.138.9:49663
> I0706 13:50:19.616061  3704 master.cpp:583] Elected as master!
> I0706 13:50:19.954679  3704 master.cpp:962] Attempting to register slave
> on vesta.apache.org at slave(101)@67.195.138.9:49663
> I0706 13:50:19.731586  3702 status_update_manager.cpp:155] New master
> detected at master@67.195.138.9:49663
> I0706 13:50:19.712584  3703 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0706 13:50:20.062224  3704 master.cpp:1929] Adding slave
> 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877
> I0706 13:50:20.284523  3703 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 571.939031ms
> I0706 13:50:20.392953  3699 slave.cpp:589] Registered with master
> master@67.195.138.9:49663; given slave ID
> 201307061350-160088899-49663-3668-0
> I0706 13:50:20.393054  3704 master.cpp:633] Registering framework
> 201307061350-160088899-49663-3668-0000 at scheduler(94)@67.195.138.9:49663
> I0706 13:50:20.499585  3703 hierarchical_allocator_process.hpp:448] Added
> slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877 (and cpus=2; mem=1024;
> ports=[31000-32000]; disk=775877 available)
> I0706 13:50:20.731552  3704 sched.cpp:222] Framework registered with
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:20.839154  3703 hierarchical_allocator_process.hpp:686]
> Performed allocation for slave 201307061350-160088899-49663-3668-0 in
> 7.476us
> I0706 13:50:21.060978  3703 hierarchical_allocator_process.hpp:335] Added
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:21.173745  3703 hierarchical_allocator_process.hpp:726]
> Offering cpus=2; mem=1024; ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0 to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:21.284070  3703 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 110.341082ms
> I0706 13:50:21.284129  3700 master.hpp:327] Adding offer
> 201307061350-160088899-49663-3668-0 with resources cpus=2; mem=1024;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:21.508411  3700 master.cpp:1310] Sending 1 offers to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:21.500586  3699 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 18.516us
> I0706 13:50:21.618593  3700 master.cpp:1543] Processing reply for offer
> 201307061350-160088899-49663-3668-0 on slave
> 201307061350-160088899-49663-3668-0 (vesta.apache.org) for framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:21.845422  3700 master.hpp:305] Adding task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> I0706 13:50:22.058199  3700 master.cpp:1662] Launching task 0 of framework
> 201307061350-160088899-49663-3668-0000 with resources cpus=1; mem=500 on
> slave 201307061350-160088899-49663-3668-0 (vesta.apache.org)
> I0706 13:50:22.058406  3700 master.hpp:337] Removing offer
> 201307061350-160088899-49663-3668-0 with resources cpus=2; mem=1024;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:22.058549  3701 slave.cpp:739] Got assigned task 0 for
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:22.058574  3705 hierarchical_allocator_process.hpp:525]
> Framework 201307061350-160088899-49663-3668-0000 left cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 unused on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:22.321369  3701 slave.cpp:837] Launching task 0 for framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:22.436779  3705 hierarchical_allocator_process.hpp:568]
> Framework 201307061350-160088899-49663-3668-0000 filtered slave
> 201307061350-160088899-49663-3668-0 for 5secs
> I0706 13:50:22.547595  3701 paths.hpp:303] Created executor directory
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738'
> I0706 13:50:22.731631  3699 hierarchical_allocator_process.hpp:796]
> Filtered cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0 for framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:22.775498  3701 slave.cpp:948] Queuing task '0' for executor
> default of framework '201307061350-160088899-49663-3668-0000
> I0706 13:50:22.775655  3704 exec.cpp:170] Executor started at:
> executor(38)@67.195.138.9:49663 with pid 3668
> I0706 13:50:22.886931  3699 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 155.323706ms
> I0706 13:50:23.000134  3701 slave.cpp:511] Successfully attached file
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738'
> I0706 13:50:23.338601  3701 slave.cpp:1395] Got registration for executor
> 'default' of framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:23.450140  3701 slave.cpp:1510] Flushing queued task 0 for
> executor 'default' of framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:23.450177  3706 exec.cpp:194] Executor registered on slave
> 201307061350-160088899-49663-3668-0
> W0706 13:50:23.588397  3700 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0706 13:50:23.673056  3706 exec.cpp:258] Executor asked to run task '0'
> I0706 13:50:23.909185  3706 exec.cpp:404] Executor sending status update
> TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:24.019520  3705 slave.cpp:1692] Handling status update
> TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of
> framework 201307061350-160088899-49663-3668-0000 from executor(38)@
> 67.195.138.9:49663
> I0706 13:50:24.129947  3705 status_update_manager.cpp:290] Received status
> update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0
> of framework 201307061350-160088899-49663-3668-0000 with checkpoint=false
> I0706 13:50:24.245395  3705 status_update_manager.cpp:450] Creating
> StatusUpdate stream for task 0 of framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:24.357918  3705 status_update_manager.cpp:336] Forwarding
> status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for
> task 0 of framework 201307061350-160088899-49663-3668-0000 to
> master@67.195.138.9:49663
> I0706 13:50:24.224131  3706 hierarchical_allocator_process.hpp:796]
> Filtered cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0 for framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:24.469468  3702 slave.cpp:1803] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> c433ed83-99f8-4e2e-8613-cf7687b27892) for task 0 of framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:24.469478  3703 master.cpp:1093] Status update from slave(101)@
> 67.195.138.9:49663: task 0 of framework
> 201307061350-160088899-49663-3668-0000 is now in state TASK_RUNNING
> I0706 13:50:24.584825  3706 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 360.758594ms
> I0706 13:50:24.696347  3702 slave.cpp:1809] Sending acknowledgement for
> status update TASK_RUNNING (UUID: c433ed83-99f8-4e2e-8613-cf7687b27892) for
> task 0 of framework 201307061350-160088899-49663-3668-0000 to executor(38)@
> 67.195.138.9:49663
> I0706 13:50:24.820129  3668 master.cpp:436] Master terminating
> I0706 13:50:25.158082  3668 master.cpp:209] Shutting down master
> I0706 13:50:25.042801  3702 status_update_manager.cpp:360] Received status
> update acknowledgement c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:25.042719  3699 exec.cpp:290] Executor received status update
> acknowledgement c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:25.158136  3700 slave.cpp:1884] master@67.195.138.9:49663exited
> W0706 13:50:25.604001  3700 slave.cpp:1887] Master disconnected! Waiting
> for a new master to be elected
> I0706 13:50:25.269656  3668 master.hpp:317] Removing task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> I0706 13:50:25.269635  3705 hierarchical_allocator_process.hpp:411]
> Deactivated framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:25.731475  3700 slave.cpp:1112] Asked to shut down framework
> 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
> I0706 13:50:26.169428  3700 slave.cpp:1137] Shutting down framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:26.280905  3700 slave.cpp:2328] Shutting down executor
> 'default' of framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:26.055472  3703 detector.cpp:420] Master detector
> (scheduler(94)@67.195.138.9:49663)  found 0 registered masters
> I0706 13:50:26.055610  3704 detector.cpp:420] Master detector (slave(101)@
> 67.195.138.9:49663)  found 0 registered masters
> I0706 13:50:26.619339  3704 detector.cpp:441] Master detector (slave(101)@
> 67.195.138.9:49663) couldn't find any masters
> I0706 13:50:26.055270  3706 detector.cpp:234] Master detector (
> master@67.195.138.9:49663) connected to ZooKeeper ...
> I0706 13:50:26.874203  3706 detector.cpp:251] Trying to create path
> '/znode' in ZooKeeper
> I0706 13:50:26.392519  3701 exec.cpp:323] Executor asked to shutdown
> I0706 13:50:26.503885  3703 detector.cpp:441] Master detector
> (scheduler(94)@67.195.138.9:49663) couldn't find any masters
> I0706 13:50:26.014909  3702 master.cpp:230] Master started on
> 67.195.138.9:49663
> I0706 13:50:26.392448  3700 slave.cpp:1343] Status update manager
> successfully handled status update acknowledgement
> c433ed83-99f8-4e2e-8613-cf7687b27892 for task 0 of framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:27.037516  3706 detector.cpp:281] Created ephemeral/sequence
> znode at '/znode/0000000006'
> I0706 13:50:27.038017  3699 detector.cpp:420] Master detector (slave(101)@
> 67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:27.141072  3704 sched.cpp:194] No master detected, waiting for
> another master
> I0706 13:50:27.141629  3703 detector.cpp:420] Master detector
> (scheduler(94)@67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:27.248498  3702 master.cpp:245] Master ID:
> 201307061350-160088899-49663-3668
> I0706 13:50:27.363991  3700 slave.cpp:563] Lost master(s) ... waiting
> I0706 13:50:27.471997  3706 detector.cpp:420] Master detector (
> master@67.195.138.9:49663)  found 1 registered masters
> I0706 13:50:27.583493  3699 detector.cpp:467] Master detector (slave(101)@
> 67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:27.802623  3703 detector.cpp:467] Master detector
> (scheduler(94)@67.195.138.9:49663)  got new master pid:
> master@67.195.138.9:49663
> W0706 13:50:27.909575  3704 master.cpp:83] No whitelist given. Advertising
> offers for all slaves
> I0706 13:50:27.909826  3705 hierarchical_allocator_process.hpp:305]
> Initializing hierarchical allocator process with master :
> master@67.195.138.9:49663
> I0706 13:50:28.136857  3706 detector.cpp:467] Master detector (
> master@67.195.138.9:49663)  got new master pid: master@67.195.138.9:49663
> I0706 13:50:28.251948  3699 slave.cpp:529] New master detected at
> master@67.195.138.9:49663
> I0706 13:50:28.339752  3702 monitor.cpp:180] Publishing resource usage for
> executor 'default' of framework '201307061350-160088899-49663-3668-0000'
> I0706 13:50:28.363417  3703 sched.cpp:177] New master at
> master@67.195.138.9:49663
> I0706 13:50:28.714254  3706 master.cpp:583] Elected as master!
> I0706 13:50:28.829726  3701 status_update_manager.cpp:155] New master
> detected at master@67.195.138.9:49663
> I0706 13:50:29.160222  3706 master.cpp:1034] Attempting to re-register
> slave 201307061350-160088899-49663-3668-0 at slave(101)@67.195.138.9:49663(
> vesta.apache.org)
> I0706 13:50:29.387094  3706 master.cpp:1929] Adding slave
> 201307061350-160088899-49663-3668-0 at vesta.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877
> I0706 13:50:29.494673  3702 slave.cpp:630] Re-registered with master
> master@67.195.138.9:49663
> I0706 13:50:29.494670  3706 master.hpp:305] Adding task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> W0706 13:50:29.723273  3706 master.cpp:2021] Possibly orphaned task 0 of
> framework 201307061350-160088899-49663-3668-0000 running on slave
> 201307061350-160088899-49663-3668-0 (vesta.apache.org)
> I0706 13:50:29.602946  3704 hierarchical_allocator_process.hpp:704] No
> resources available to allocate!
> I0706 13:50:29.960464  3704 hierarchical_allocator_process.hpp:666]
> Performed allocation for 0 slaves in 357.519618ms
> I0706 13:50:30.051612  3701 master.cpp:675] Re-registering framework
> 201307061350-160088899-49663-3668-0000 at scheduler(94)@67.195.138.9:49663
> I0706 13:50:30.073490  3704 hierarchical_allocator_process.hpp:448] Added
> slave 201307061350-160088899-49663-3668-0 (vesta.apache.org) with cpus=2;
> mem=1024; ports=[31000-32000]; disk=775877 (and cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 available)
> I0706 13:50:30.180987  3700 sched.cpp:246] Framework re-registered with
> 201307061350-160088899-49663-3668-0000
> W0706 13:50:30.181030  3701 slave.cpp:1273] Ignoring updating pid for
> framework 201307061350-160088899-49663-3668-0000 because it is terminating
> I0706 13:50:30.292280  3704 hierarchical_allocator_process.hpp:686]
> Performed allocation for slave 201307061350-160088899-49663-3668-0 in
> 5.705us
> I0706 13:50:30.638453  3704 hierarchical_allocator_process.hpp:335] Added
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:30.749846  3704 hierarchical_allocator_process.hpp:726]
> Offering cpus=1; mem=524; ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0 to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:30.861340  3704 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 111.513896ms
> I0706 13:50:30.861379  3705 master.hpp:327] Adding offer
> 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:31.080117  3705 master.cpp:1310] Sending 1 offers to framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.073792  3706 hierarchical_allocator_process.hpp:666]
> Performed allocation for 1 slaves in 13.353us
> I0706 13:50:31.199796  3704 sched.cpp:427] Stopping framework
> '201307061350-160088899-49663-3668-0000'
> I0706 13:50:31.199822  3703 master.cpp:436] Master terminating
> I0706 13:50:31.393146  3702 slave.cpp:2397] Killing executor 'default' of
> framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.545691  3668 master.cpp:209] Shutting down master
> I0706 13:50:31.816084  3701 hierarchical_allocator_process.hpp:411]
> Deactivated framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.816104  3668 master.hpp:317] Removing task 0 with resources
> cpus=1; mem=500 on slave 201307061350-160088899-49663-3668-0
> I0706 13:50:31.816349  3668 master.hpp:337] Removing offer
> 201307061350-160088899-49663-3668-0 with resources cpus=1; mem=524;
> ports=[31000-32000]; disk=775877 on slave
> 201307061350-160088899-49663-3668-0
> I0706 13:50:31.816362  3705 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[];
> disk=0) on slave 201307061350-160088899-49663-3668-0 from framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.816519  3705 hierarchical_allocator_process.hpp:615]
> Recovered cpus=1; mem=524; ports=[31000-32000]; disk=775877 (total
> allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=775877) on slave
> 201307061350-160088899-49663-3668-0 from framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.816632  3705 hierarchical_allocator_process.hpp:366]
> Removed framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.816680  3705 hierarchical_allocator_process.hpp:476]
> Removed slave 201307061350-160088899-49663-3668-0
> I0706 13:50:31.815983  3702 slave.cpp:485] Slave asked to shut down by
> master@67.195.138.9:49663
> I0706 13:50:31.816854  3702 slave.cpp:1112] Asked to shut down framework
> 201307061350-160088899-49663-3668-0000 by master@67.195.138.9:49663
> W0706 13:50:31.816872  3702 slave.cpp:1133] Ignoring shutdown framework
> 201307061350-160088899-49663-3668-0000 because it is terminating
> I0706 13:50:31.816896  3702 slave.cpp:1884] master@67.195.138.9:49663exited
> W0706 13:50:31.816910  3702 slave.cpp:1887] Master disconnected! Waiting
> for a new master to be elected
> I0706 13:50:31.816931  3702 slave.cpp:2060] Executor 'default' of
> framework 201307061350-160088899-49663-3668-0000 has exited with status 0
> I0706 13:50:31.817004  3702 slave.cpp:2195] Cleaning up executor 'default'
> of framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.817122  3705 gc.cpp:56] Scheduling
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default/runs/9800e6bb-128c-44a3-b5ec-06558e1cf738'
> for removal
> I0706 13:50:31.817250  3702 slave.cpp:2259] Cleaning up framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.817265  3705 gc.cpp:56] Scheduling
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000/executors/default'
> for removal
> I0706 13:50:31.817380  3701 status_update_manager.cpp:252] Closing status
> update streams for framework 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.817420  3701 status_update_manager.cpp:481] Cleaning up
> status update stream for task 0 of framework
> 201307061350-160088899-49663-3668-0000
> I0706 13:50:31.817431  3702 slave.cpp:440] Slave terminating
> I0706 13:50:31.817450  3706 gc.cpp:56] Scheduling
> '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_JRiNwV/slaves/201307061350-160088899-49663-3668-0/frameworks/201307061350-160088899-49663-3668-0000'
> for removal
> [       OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (14739 ms)
> I0706 13:50:31.875761  3668 zookeeper_test_server.cpp:93] Shutdown
> ZooKeeperTestServer on port 57500
> [----------] 2 tests from AllocatorZooKeeperTest/0 (30373 ms total)
>
> [----------] Global test environment tear-down
> [==========] 198 tests from 37 test cases ran. (201559 ms total)
> [  PASSED  ] 197 tests.
> [  FAILED  ] 1 test, listed below:
> [  FAILED  ] AllocatorTest/0.SchedulerFailover, where TypeParam =
> mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter,
> mesos::internal::master::allocator::DRFSorter>
>
>  1 FAILED TEST
> make[3]: *** [check-local] Error 1
> make[3]: Leaving directory
> `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
> make[2]: *** [check-am] Error 2
> make[2]: Leaving directory
> `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
> make[1]: *** [check] Error 2
> make[1]: Leaving directory
> `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
> make: *** [check-recursive] Error 1
> Build step 'Execute shell' marked build as failure
>