See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1233/>
------------------------------------------ [...truncated 13846 lines...] I0624 02:56:21.616277 364 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 03ffcf19-70ff-4287-a3d9-778a93eede3b) for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.616439 364 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 03ffcf19-70ff-4287-a3d9-778a93eede3b) for task 0 of framework 201306240256-143311683-38688-32750-0000 with checkpoint=false I0624 02:56:21.616503 364 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.616662 364 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 03ffcf19-70ff-4287-a3d9-778a93eede3b) for task 0 of framework 201306240256-143311683-38688-32750-0000 to [email protected]:38688 I0624 02:56:21.616763 364 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 03ffcf19-70ff-4287-a3d9-778a93eede3b) for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.616770 366 master.cpp:1022] Status update from slave(99)@67.195.138.8:38688: task 0 of framework 201306240256-143311683-38688-32750-0000 is now in state TASK_RUNNING I0624 02:56:21.616859 364 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 03ffcf19-70ff-4287-a3d9-778a93eede3b) for task 0 of framework 201306240256-143311683-38688-32750-0000 to executor(37)@67.195.138.8:38688 I0624 02:56:21.617058 363 exec.cpp:290] Executor received status update acknowledgement 03ffcf19-70ff-4287-a3d9-778a93eede3b for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.617144 364 status_update_manager.cpp:360] Received status update acknowledgement 03ffcf19-70ff-4287-a3d9-778a93eede3b for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.617355 365 master.cpp:385] Master terminating I0624 02:56:21.617390 361 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 03ffcf19-70ff-4287-a3d9-778a93eede3b for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.617573 32750 master.cpp:207] Shutting down master I0624 02:56:21.617631 361 slave.cpp:1885] [email protected]:38688 exited W0624 02:56:21.617715 361 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0624 02:56:21.617724 362 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.617728 32750 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:21.617842 361 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by [email protected]:38688 I0624 02:56:21.618017 361 slave.cpp:1138] Shutting down framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.618098 361 slave.cpp:2329] Shutting down executor 'default' of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.618151 364 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21412) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.618201 365 exec.cpp:323] Executor asked to shutdown I0624 02:56:21.618306 364 hierarchical_allocator_process.hpp:367] Removed framework 201306240256-143311683-38688-32750-0000 I0624 02:56:21.619287 359 master.cpp:228] Master started on 67.195.138.8:38688 I0624 02:56:21.619346 359 master.cpp:243] Master ID: 201306240256-143311683-38688-32750 I0624 02:56:21.620472 366 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:38688) found 0 registered masters I0624 02:56:21.620563 362 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38688) found 0 registered masters W0624 02:56:21.625742 365 master.cpp:83] No whitelist given. Advertising offers for all slaves I0624 02:56:21.621731 361 detector.cpp:234] Master detector ([email protected]:38688) connected to ZooKeeper ... I0624 02:56:21.625877 361 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0624 02:56:21.625855 363 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38688 I0624 02:56:21.625684 366 detector.cpp:441] Master detector (scheduler(90)@67.195.138.8:38688) couldn't find any masters I0624 02:56:21.626171 366 sched.cpp:194] No master detected, waiting for another master I0624 02:56:21.625757 362 detector.cpp:441] Master detector (slave(99)@67.195.138.8:38688) couldn't find any masters I0624 02:56:21.626349 362 slave.cpp:574] Lost master(s) ... waiting I0624 02:56:21.627639 361 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0624 02:56:21.627940 362 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38688) found 1 registered masters I0624 02:56:21.628160 359 detector.cpp:420] Master detector (scheduler(90)@67.195.138.8:38688) found 1 registered masters I0624 02:56:21.628304 361 detector.cpp:420] Master detector ([email protected]:38688) found 1 registered masters I0624 02:56:21.634204 362 detector.cpp:467] Master detector (slave(99)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:21.634345 362 slave.cpp:540] New master detected at [email protected]:38688 I0624 02:56:21.634413 364 status_update_manager.cpp:155] New master detected at [email protected]:38688 I0624 02:56:21.634709 359 detector.cpp:467] Master detector (scheduler(90)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:21.634814 359 sched.cpp:177] New master at [email protected]:38688 W0624 02:56:21.634876 359 master.cpp:591] Ignoring re-register framework message since not elected yet I0624 02:56:21.635015 361 detector.cpp:467] Master detector ([email protected]:38688) got new master pid: [email protected]:38688 I0624 02:56:21.635119 361 master.cpp:526] Elected as master! I0624 02:56:22.611117 359 master.cpp:604] Re-registering framework 201306240256-143311683-38688-32750-0000 at scheduler(90)@67.195.138.8:38688 I0624 02:56:22.611313 359 sched.cpp:246] Framework re-registered with 201306240256-143311683-38688-32750-0000 I0624 02:56:22.611450 363 hierarchical_allocator_process.hpp:327] Added framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.611500 363 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 02:56:22.611556 363 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 54.856us I0624 02:56:22.626091 364 hierarchical_allocator_process.hpp:705] No resources available to allocate! I0624 02:56:22.626140 364 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 54.744us I0624 02:56:22.635326 359 master.cpp:963] Attempting to re-register slave 201306240256-143311683-38688-32750-0 at slave(99)@67.195.138.8:38688 (minerva.apache.org) I0624 02:56:22.635367 359 master.cpp:1851] Adding slave 201306240256-143311683-38688-32750-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 I0624 02:56:22.635491 366 slave.cpp:636] Re-registered with master [email protected]:38688 I0624 02:56:22.635552 359 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 W0624 02:56:22.635753 365 slave.cpp:1274] Ignoring updating pid for framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:22.635865 360 hierarchical_allocator_process.hpp:449] Added slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 (and cpus=1; mem=524; ports=[31000-32000]; disk=21412 available) I0624 02:56:22.635989 360 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.636132 360 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306240256-143311683-38688-32750-0 in 164.591us I0624 02:56:22.636219 360 master.hpp:313] Adding offer 201306240256-143311683-38688-32750-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:22.636301 360 master.cpp:1239] Sending 1 offers to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.636641 360 sched.cpp:427] Stopping framework '201306240256-143311683-38688-32750-0000' I0624 02:56:22.636656 32750 master.cpp:385] Master terminating I0624 02:56:22.636777 32750 master.cpp:207] Shutting down master I0624 02:56:22.636868 32750 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:22.636903 366 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.637006 32750 master.hpp:323] Removing offer 201306240256-143311683-38688-32750-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:22.637140 360 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.637306 360 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=21412 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21412) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.636773 365 slave.cpp:496] Slave asked to shut down by [email protected]:38688 I0624 02:56:22.637428 360 hierarchical_allocator_process.hpp:367] Removed framework 201306240256-143311683-38688-32750-0000 I0624 02:56:22.637431 365 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by [email protected]:38688 W0624 02:56:22.637646 365 slave.cpp:1134] Ignoring shutdown framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:22.637608 360 hierarchical_allocator_process.hpp:477] Removed slave 201306240256-143311683-38688-32750-0 I0624 02:56:22.641284 362 detector.cpp:420] Master detector (slave(99)@67.195.138.8:38688) found 0 registered masters I0624 02:56:22.645629 365 slave.cpp:451] Slave terminating I0624 02:56:22.645771 362 detector.cpp:441] Master detector (slave(99)@67.195.138.8:38688) couldn't find any masters I0624 02:56:22.645824 365 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by @0.0.0.0:0 W0624 02:56:22.645969 365 slave.cpp:1134] Ignoring shutdown framework 201306240256-143311683-38688-32750-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2055 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0624 02:56:22.647074 366 master.cpp:228] Master started on 67.195.138.8:38688 2013-06-24 02:56:22,647:32750(0x2af799602700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-06-24 02:56:22,647:32750(0x2af799602700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-06-24 02:56:22,648:32750(0x2af799602700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-06-24 02:56:22,648:32750(0x2af799602700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-06-24 02:56:22,648:32750(0x2af799602700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013 2013-06-24 02:56:22,648:32750(0x2af799602700):ZOO_INFO@log_env@679: Client environment:user.name=(null) I0624 02:56:22.647137 366 master.cpp:243] Master ID: 201306240256-143311683-38688-32750 I0624 02:56:22.647768 359 slave.cpp:219] Slave started on 100)@67.195.138.8:38688 I0624 02:56:22.649019 360 detector.cpp:234] Master detector ([email protected]:38688) connected to ZooKeeper ... I0624 02:56:22.657773 360 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0624 02:56:22.650229 365 detector.cpp:234] Master detector (scheduler(91)@67.195.138.8:38688) connected to ZooKeeper ... I0624 02:56:22.657695 359 slave.cpp:220] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=21412 W0624 02:56:22.657728 362 master.cpp:83] No whitelist given. Advertising offers for all slaves I0624 02:56:22.657742 361 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38688 I0624 02:56:22.649718 363 detector.cpp:234] Master detector (slave(100)@67.195.138.8:38688) connected to ZooKeeper ... I0624 02:56:22.657990 365 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0624 02:56:22.658587 363 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0624 02:56:22.658854 366 slave.cpp:401] Finished recovery I0624 02:56:22.660049 360 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0624 02:56:22.660408 365 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38688) found 1 registered masters I0624 02:56:22.660601 360 detector.cpp:420] Master detector ([email protected]:38688) found 1 registered masters I0624 02:56:22.660748 363 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38688) found 1 registered masters I0624 02:56:22.666358 365 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:22.666517 365 sched.cpp:177] New master at [email protected]:38688 W0624 02:56:22.666597 365 master.cpp:549] Ignoring register framework message since not elected yet I0624 02:56:22.666879 360 detector.cpp:467] Master detector ([email protected]:38688) got new master pid: [email protected]:38688 I0624 02:56:22.666981 360 master.cpp:526] Elected as master! I0624 02:56:22.667227 363 detector.cpp:467] Master detector (slave(100)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:22.667441 363 slave.cpp:540] New master detected at [email protected]:38688 I0624 02:56:22.667520 361 status_update_manager.cpp:155] New master detected at [email protected]:38688 I0624 02:56:22.667603 366 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(100)@67.195.138.8:38688 I0624 02:56:22.667677 366 master.cpp:1851] Adding slave 201306240256-143311683-38688-32750-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 I0624 02:56:22.667791 360 slave.cpp:600] Registered with master [email protected]:38688; given slave ID 201306240256-143311683-38688-32750-0 I0624 02:56:22.667944 362 hierarchical_allocator_process.hpp:449] Added slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 (and cpus=2; mem=1024; ports=[31000-32000]; disk=21412 available) I0624 02:56:22.668028 362 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 02:56:22.668088 362 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306240256-143311683-38688-32750-0 in 61.065us I0624 02:56:23.658687 360 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 02:56:23.658782 360 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 109.3us I0624 02:56:23.666931 363 master.cpp:569] Registering framework 201306240256-143311683-38688-32750-0000 at scheduler(91)@67.195.138.8:38688 I0624 02:56:23.667045 363 sched.cpp:222] Framework registered with 201306240256-143311683-38688-32750-0000 I0624 02:56:23.667114 364 hierarchical_allocator_process.hpp:327] Added framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.667212 364 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.667337 364 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 140.359us I0624 02:56:23.667394 366 master.hpp:313] Adding offer 201306240256-143311683-38688-32750-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.667485 366 master.cpp:1239] Sending 1 offers to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.667798 365 master.cpp:1472] Processing reply for offer 201306240256-143311683-38688-32750-0 on slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) for framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.667961 365 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.668004 365 master.cpp:1591] Launching task 0 of framework 201306240256-143311683-38688-32750-0000 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) I0624 02:56:23.668114 361 slave.cpp:740] Got assigned task 0 for framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.668174 365 master.hpp:323] Removing offer 201306240256-143311683-38688-32750-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.668299 365 hierarchical_allocator_process.hpp:526] Framework 201306240256-143311683-38688-32750-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=21412 unused on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.668339 361 slave.cpp:838] Launching task 0 for framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.668431 365 hierarchical_allocator_process.hpp:569] Framework 201306240256-143311683-38688-32750-0000 filtered slave 201306240256-143311683-38688-32750-0 for 5secs I0624 02:56:23.669816 361 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_IeIOKu/slaves/201306240256-143311683-38688-32750-0/frameworks/201306240256-143311683-38688-32750-0000/executors/default/runs/b1f572e9-09f7-4d1a-84f9-6e3b6e1cef32' I0624 02:56:23.670011 361 slave.cpp:949] Queuing task '0' for executor default of framework '201306240256-143311683-38688-32750-0000 I0624 02:56:23.670140 362 slave.cpp:522] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_IeIOKu/slaves/201306240256-143311683-38688-32750-0/frameworks/201306240256-143311683-38688-32750-0000/executors/default/runs/b1f572e9-09f7-4d1a-84f9-6e3b6e1cef32' I0624 02:56:23.670181 364 exec.cpp:170] Executor started at: executor(38)@67.195.138.8:38688 with pid 32750 I0624 02:56:23.670282 364 slave.cpp:1396] Got registration for executor 'default' of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.670501 364 slave.cpp:1511] Flushing queued task 0 for executor 'default' of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.670537 365 exec.cpp:194] Executor registered on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.670689 365 exec.cpp:258] Executor asked to run task '0' I0624 02:56:23.671896 365 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.671982 365 slave.cpp:1693] Handling status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.672108 365 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 with checkpoint=false I0624 02:56:23.672145 365 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.672266 365 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 to [email protected]:38688 I0624 02:56:23.672366 359 master.cpp:1022] Status update from slave(100)@67.195.138.8:38688: task 0 of framework 201306240256-143311683-38688-32750-0000 is now in state TASK_RUNNING I0624 02:56:23.672377 365 slave.cpp:1804] Status update manager successfully handled status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.672459 365 slave.cpp:1810] Sending acknowledgement for status update TASK_RUNNING (UUID: 7fa460fb-cd17-4f97-95be-f9f930f9d7af) for task 0 of framework 201306240256-143311683-38688-32750-0000 to executor(38)@67.195.138.8:38688 I0624 02:56:23.672549 364 exec.cpp:290] Executor received status update acknowledgement 7fa460fb-cd17-4f97-95be-f9f930f9d7af for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.672904 32750 master.cpp:385] Master terminating I0624 02:56:23.673005 32750 master.cpp:207] Shutting down master I0624 02:56:23.673106 360 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.673105 359 slave.cpp:1885] [email protected]:38688 exited I0624 02:56:23.673115 32750 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:23.673218 361 status_update_manager.cpp:360] Received status update acknowledgement 7fa460fb-cd17-4f97-95be-f9f930f9d7af for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.673495 364 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21412) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.673647 364 hierarchical_allocator_process.hpp:367] Removed framework 201306240256-143311683-38688-32750-0000 W0624 02:56:23.673269 359 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0624 02:56:23.673795 359 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by [email protected]:38688 I0624 02:56:23.673841 359 slave.cpp:1138] Shutting down framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.673912 359 slave.cpp:2329] Shutting down executor 'default' of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.674012 360 exec.cpp:323] Executor asked to shutdown I0624 02:56:23.674031 359 slave.cpp:1344] Status update manager successfully handled status update acknowledgement 7fa460fb-cd17-4f97-95be-f9f930f9d7af for task 0 of framework 201306240256-143311683-38688-32750-0000 I0624 02:56:23.674435 361 master.cpp:228] Master started on 67.195.138.8:38688 I0624 02:56:23.674537 361 master.cpp:243] Master ID: 201306240256-143311683-38688-32750 W0624 02:56:23.674736 364 master.cpp:83] No whitelist given. Advertising offers for all slaves I0624 02:56:23.674864 364 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : [email protected]:38688 I0624 02:56:23.676785 365 detector.cpp:234] Master detector ([email protected]:38688) connected to ZooKeeper ... I0624 02:56:23.676826 365 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0624 02:56:23.677002 361 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38688) found 0 registered masters I0624 02:56:23.677157 363 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38688) found 0 registered masters I0624 02:56:23.681741 361 detector.cpp:441] Master detector (scheduler(91)@67.195.138.8:38688) couldn't find any masters I0624 02:56:23.681865 363 detector.cpp:441] Master detector (slave(100)@67.195.138.8:38688) couldn't find any masters I0624 02:56:23.682023 364 sched.cpp:194] No master detected, waiting for another master I0624 02:56:23.682076 363 slave.cpp:574] Lost master(s) ... waiting I0624 02:56:23.683306 365 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0624 02:56:23.683697 364 detector.cpp:420] Master detector (scheduler(91)@67.195.138.8:38688) found 1 registered masters I0624 02:56:23.683799 359 detector.cpp:420] Master detector (slave(100)@67.195.138.8:38688) found 1 registered masters I0624 02:56:23.683974 365 detector.cpp:420] Master detector ([email protected]:38688) found 1 registered masters I0624 02:56:23.690341 364 detector.cpp:467] Master detector (scheduler(91)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:23.690464 365 detector.cpp:467] Master detector ([email protected]:38688) got new master pid: [email protected]:38688 I0624 02:56:23.690594 366 sched.cpp:177] New master at [email protected]:38688 I0624 02:56:23.690630 365 master.cpp:526] Elected as master! I0624 02:56:23.690712 359 detector.cpp:467] Master detector (slave(100)@67.195.138.8:38688) got new master pid: [email protected]:38688 I0624 02:56:23.690883 359 slave.cpp:540] New master detected at [email protected]:38688 I0624 02:56:23.690969 364 status_update_manager.cpp:155] New master detected at [email protected]:38688 I0624 02:56:23.691123 361 master.cpp:963] Attempting to re-register slave 201306240256-143311683-38688-32750-0 at slave(100)@67.195.138.8:38688 (minerva.apache.org) I0624 02:56:23.691198 361 master.cpp:1851] Adding slave 201306240256-143311683-38688-32750-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 I0624 02:56:23.691357 360 slave.cpp:636] Re-registered with master [email protected]:38688 I0624 02:56:23.691403 361 master.hpp:291] Adding task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 W0624 02:56:23.691514 361 master.cpp:1943] Possibly orphaned task 0 of framework 201306240256-143311683-38688-32750-0000 running on slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) I0624 02:56:23.691826 361 hierarchical_allocator_process.hpp:449] Added slave 201306240256-143311683-38688-32750-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21412 (and cpus=1; mem=524; ports=[31000-32000]; disk=21412 available) I0624 02:56:23.691897 361 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 02:56:23.691958 361 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306240256-143311683-38688-32750-0 in 62.218us I0624 02:56:24.667089 360 master.cpp:604] Re-registering framework 201306240256-143311683-38688-32750-0000 at scheduler(91)@67.195.138.8:38688 I0624 02:56:24.667274 361 sched.cpp:246] Framework re-registered with 201306240256-143311683-38688-32750-0000 W0624 02:56:24.667314 360 slave.cpp:1274] Ignoring updating pid for framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:24.667404 361 hierarchical_allocator_process.hpp:327] Added framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.667486 361 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.667644 361 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 182.051us I0624 02:56:24.667722 359 master.hpp:313] Adding offer 201306240256-143311683-38688-32750-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:24.667847 359 master.cpp:1239] Sending 1 offers to framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.668225 365 sched.cpp:427] Stopping framework '201306240256-143311683-38688-32750-0000' I0624 02:56:24.668233 32750 master.cpp:385] Master terminating I0624 02:56:24.668419 363 slave.cpp:496] Slave asked to shut down by [email protected]:38688 I0624 02:56:24.668442 32750 master.cpp:207] Shutting down master I0624 02:56:24.668488 363 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by [email protected]:38688 W0624 02:56:24.668658 363 slave.cpp:1134] Ignoring shutdown framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:24.668687 365 hierarchical_allocator_process.hpp:412] Deactivated framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.668709 32750 master.hpp:303] Removing task 0 with resources cpus=1; mem=500 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:24.668727 363 slave.cpp:1885] [email protected]:38688 exited W0624 02:56:24.668942 363 slave.cpp:1888] Master disconnected! Waiting for a new master to be elected I0624 02:56:24.669025 32750 master.hpp:323] Removing offer 201306240256-143311683-38688-32750-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21412 on slave 201306240256-143311683-38688-32750-0 I0624 02:56:24.669117 364 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.673924 364 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=21412 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21412) on slave 201306240256-143311683-38688-32750-0 from framework 201306240256-143311683-38688-32750-0000 I0624 02:56:24.673671 363 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by [email protected]:38688 I0624 02:56:24.674079 364 hierarchical_allocator_process.hpp:367] Removed framework 201306240256-143311683-38688-32750-0000 W0624 02:56:24.674147 363 slave.cpp:1134] Ignoring shutdown framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:24.674300 364 hierarchical_allocator_process.hpp:477] Removed slave 201306240256-143311683-38688-32750-0 I0624 02:56:24.674546 359 slave.cpp:451] Slave terminating I0624 02:56:24.674640 359 slave.cpp:1113] Asked to shut down framework 201306240256-143311683-38688-32750-0000 by @0.0.0.0:0 W0624 02:56:24.674692 359 slave.cpp:1134] Ignoring shutdown framework 201306240256-143311683-38688-32750-0000 because it is terminating I0624 02:56:24.675195 366 hierarchical_allocator_process.hpp:700] No users to allocate resources! I0624 02:56:24.675245 366 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 73.116us [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2035 ms) I0624 02:56:24.683157 32750 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 37698 [----------] 2 tests from AllocatorZooKeeperTest/0 (4091 ms total) [----------] Global test environment tear-down [==========] 196 tests from 37 test cases ran. (130888 ms total) [ PASSED ] 194 tests. [ FAILED ] 2 tests, listed below: [ FAILED ] ZooKeeperMasterDetectorTest.MasterDetectorExpireSlaveZKSessionNewMaster [ FAILED ] AllocatorTest/0.SchedulerFailover, where TypeParam = mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter> 2 FAILED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
