Reopened: https://issues.apache.org/jira/browse/MESOS-410
On Sat, Jul 6, 2013 at 6:50 AM, Apache Jenkins Server < [email protected]> 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 [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]: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 ( > [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]: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 [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]: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 >
