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 [email protected]: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 ([email protected]: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 : [email protected]: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 ([email protected]: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: [email protected]: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: [email protected]:49663 I0706 13:50:11.907588 3699 detector.cpp:467] Master detector ([email protected]:49663) got new master pid: [email protected]:49663 I0706 13:50:12.058480 3703 sched.cpp:177] New master at [email protected]:49663 I0706 13:50:12.285539 3702 slave.cpp:529] New master detected at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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] [email protected]: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 ([email protected]: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 : [email protected]: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 ([email protected]: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: [email protected]:49663 I0706 13:50:19.166283 3706 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:49663) got new master pid: [email protected]:49663 I0706 13:50:19.277853 3704 detector.cpp:467] Master detector ([email protected]:49663) got new master pid: [email protected]:49663 I0706 13:50:19.388984 3702 slave.cpp:529] New master detected at [email protected]:49663 I0706 13:50:19.504180 3706 sched.cpp:177] New master at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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] [email protected]: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 [email protected]: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 ([email protected]: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 ([email protected]: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: [email protected]:49663 I0706 13:50:27.802623 3703 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:49663) got new master pid: [email protected]: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 : [email protected]:49663 I0706 13:50:28.136857 3706 detector.cpp:467] Master detector ([email protected]:49663) got new master pid: [email protected]:49663 I0706 13:50:28.251948 3699 slave.cpp:529] New master detected at [email protected]: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 [email protected]: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 [email protected]: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 [email protected]: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 [email protected]:49663 I0706 13:50:31.816854 3702 slave.cpp:1112] Asked to shut down framework 201307061350-160088899-49663-3668-0000 by [email protected]: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] [email protected]: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
