See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1674/changes>
Changes: [vinod] Removed references of jenkins from Makefile.am and configure.ac. ------------------------------------------ [...truncated 17050 lines...] I0920 08:27:37.085314 25392 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 to [email protected]:59397 I0920 08:27:37.085510 25397 master.cpp:1274] Status update TASK_RUNNING (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 from slave(131)@67.195.138.9:59397 I0920 08:27:37.085636 25394 sched.cpp:395] Scheduler::statusUpdate took 19.301us I0920 08:27:37.085855 25371 master.cpp:481] Master terminating I0920 08:27:37.085952 25371 master.cpp:206] Shutting down master I0920 08:27:37.085592 25396 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:37.086071 25371 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:37.086117 25396 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 to executor(40)@67.195.138.9:59397 I0920 08:27:37.086308 25371 master.cpp:237] Removing slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:37.086479 25391 exec.cpp:331] Executor received status update acknowledgement ea0ad6eb-3cc0-43d6-a7bd-7756287120f3 for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:37.086524 25396 slave.cpp:1973] [email protected]:59397 exited W0920 08:27:37.086659 25396 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected I0920 08:27:37.086679 25390 status_update_manager.cpp:375] Received status update acknowledgement (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:37.086344 25392 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]) on slave 201309200827-160088899-59397-25371-0 from framework 201309200827-160088899-59397-25371-0000 I0920 08:27:37.087044 25395 slave.cpp:1386] Status update manager successfully handled status update acknowledgement (UUID: ea0ad6eb-3cc0-43d6-a7bd-7756287120f3) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:37.087888 25392 master.cpp:265] Master started on 67.195.138.9:59397 I0920 08:27:37.088121 25392 master.cpp:280] Master ID: 201309200827-160088899-59397-25371 I0920 08:27:37.088340 25396 master.cpp:80] No whitelist given. Advertising offers for all slaves I0920 08:27:37.088552 25395 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : [email protected]:59397 I0920 08:27:37.106231 25393 detector.cpp:234] Master detector ([email protected]:59397) connected to ZooKeeper ... I0920 08:27:37.106326 25393 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0920 08:27:37.106402 25396 detector.cpp:420] Master detector (slave(131)@67.195.138.9:59397) found 0 registered masters I0920 08:27:37.106489 25396 detector.cpp:441] Master detector (slave(131)@67.195.138.9:59397) couldn't find any masters I0920 08:27:37.106590 25396 slave.cpp:577] Lost master(s) ... waiting I0920 08:27:37.106662 25390 detector.cpp:420] Master detector (scheduler(113)@67.195.138.9:59397) found 0 registered masters I0920 08:27:37.106789 25390 detector.cpp:441] Master detector (scheduler(113)@67.195.138.9:59397) couldn't find any masters I0920 08:27:37.106892 25390 sched.cpp:202] No master detected, waiting for another master I0920 08:27:37.106992 25390 sched.cpp:214] Scheduler::disconnected took 9.864us I0920 08:27:37.114070 25393 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0920 08:27:37.114784 25397 detector.cpp:420] Master detector (scheduler(113)@67.195.138.9:59397) found 1 registered masters I0920 08:27:37.114967 25391 detector.cpp:420] Master detector (slave(131)@67.195.138.9:59397) found 1 registered masters I0920 08:27:37.115417 25397 detector.cpp:467] Master detector (scheduler(113)@67.195.138.9:59397) got new master pid: [email protected]:59397 I0920 08:27:37.115461 25393 detector.cpp:420] Master detector ([email protected]:59397) found 1 registered masters I0920 08:27:37.115594 25397 sched.cpp:178] New master at [email protected]:59397 I0920 08:27:37.115681 25391 detector.cpp:467] Master detector (slave(131)@67.195.138.9:59397) got new master pid: [email protected]:59397 W0920 08:27:37.115813 25396 master.cpp:716] Ignoring re-register framework message since not elected yet I0920 08:27:37.115885 25391 slave.cpp:543] New master detected at [email protected]:59397 I0920 08:27:37.116116 25394 status_update_manager.cpp:157] New master detected at [email protected]:59397 I0920 08:27:37.116143 25393 detector.cpp:467] Master detector ([email protected]:59397) got new master pid: [email protected]:59397 I0920 08:27:37.116351 25393 master.cpp:644] Elected as master! I0920 08:27:38.076817 25391 master.cpp:736] Re-registering framework 201309200827-160088899-59397-25371-0000 at scheduler(113)@67.195.138.9:59397 I0920 08:27:38.077080 25395 sched.cpp:237] Framework registered with 201309200827-160088899-59397-25371-0000 I0920 08:27:38.077159 25395 sched.cpp:251] Scheduler::registered took 9.954us I0920 08:27:38.077163 25391 hierarchical_allocator_process.hpp:321] Added framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.077342 25391 hierarchical_allocator_process.hpp:687] No resources available to allocate! I0920 08:27:38.077389 25391 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 49.59us I0920 08:27:38.081943 25392 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201309200827-160088899-59397-25371-0000' I0920 08:27:38.088958 25395 hierarchical_allocator_process.hpp:687] No resources available to allocate! I0920 08:27:38.089046 25395 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 92.457us I0920 08:27:38.117230 25391 master.cpp:1175] Attempting to re-register slave 201309200827-160088899-59397-25371-0 at slave(131)@67.195.138.9:59397 (vesta.apache.org) I0920 08:27:38.117331 25391 master.cpp:2165] Adding slave 201309200827-160088899-59397-25371-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] I0920 08:27:38.117492 25391 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:38.117676 25391 slave.cpp:644] Re-registered with master [email protected]:59397 I0920 08:27:38.117753 25391 slave.cpp:1332] Updating framework 201309200827-160088899-59397-25371-0000 pid to scheduler(113)@67.195.138.9:59397 I0920 08:27:38.117964 25391 hierarchical_allocator_process.hpp:434] Added slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] available) I0920 08:27:38.118077 25391 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.118242 25391 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309200827-160088899-59397-25371-0 in 203.576us I0920 08:27:38.118381 25392 master.hpp:347] Adding offer 201309200827-160088899-59397-25371-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:38.118540 25392 master.cpp:1462] Sending 1 offers to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.118753 25392 sched.cpp:340] Scheduler::resourceOffers took 21.98us I0920 08:27:38.119071 25390 sched.cpp:498] Stopping framework '201309200827-160088899-59397-25371-0000' I0920 08:27:38.119072 25394 master.cpp:481] Master terminating I0920 08:27:38.119298 25394 slave.cpp:1973] [email protected]:59397 exited I0920 08:27:38.119314 25371 master.cpp:206] Shutting down master W0920 08:27:38.119405 25394 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected I0920 08:27:38.119540 25371 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:38.119801 25371 master.hpp:357] Removing offer 201309200827-160088899-59397-25371-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:38.119938 25371 master.cpp:237] Removing slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:38.120076 25392 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309200827-160088899-59397-25371-0 from framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.120657 25371 slave.cpp:454] Slave terminating I0920 08:27:38.120765 25371 slave.cpp:1167] Asked to shut down framework 201309200827-160088899-59397-25371-0000 by @0.0.0.0:0 I0920 08:27:38.120820 25371 slave.cpp:1192] Shutting down framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.120878 25371 slave.cpp:2426] Shutting down executor 'default' of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.120965 25396 exec.cpp:371] Executor asked to shutdown GMOCK WARNING: Uninteresting mock function call - returning directly. Function call: shutdown(0x2ada34017600) Stack trace: I0920 08:27:38.121083 25396 exec.cpp:386] Executor::shutdown took 23.878us [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2128 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0920 08:27:38.122149 25393 master.cpp:265] Master started on 67.195.138.9:59397 I0920 08:27:38.122243 25393 master.cpp:280] Master ID: 201309200827-160088899-59397-25371 I0920 08:27:38.122473 25396 master.cpp:80] No whitelist given. Advertising offers for all slaves I0920 08:27:38.122606 25390 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : [email protected]:59397 I0920 08:27:38.123005 25395 slave.cpp:108] Slave started on 132)@67.195.138.9:59397 I0920 08:27:38.123154 25395 slave.cpp:208] Slave resources: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013 2013-09-20 08:27:38,123:25371(0x2ada25ccb700):ZOO_INFO@log_env@679: Client environment:user.name=(null) I0920 08:27:38.123965 25392 slave.cpp:399] Finished recovery I0920 08:27:38.166183 25395 detector.cpp:234] Master detector ([email protected]:59397) connected to ZooKeeper ... I0920 08:27:38.166259 25395 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0920 08:27:38.166669 25396 detector.cpp:234] Master detector (slave(132)@67.195.138.9:59397) connected to ZooKeeper ... I0920 08:27:38.166798 25396 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0920 08:27:38.167191 25390 detector.cpp:234] Master detector (scheduler(114)@67.195.138.9:59397) connected to ZooKeeper ... I0920 08:27:38.167284 25390 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0920 08:27:38.181639 25395 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0920 08:27:38.182051 25396 detector.cpp:420] Master detector (slave(132)@67.195.138.9:59397) found 1 registered masters I0920 08:27:38.182240 25395 detector.cpp:420] Master detector ([email protected]:59397) found 1 registered masters I0920 08:27:38.182632 25396 detector.cpp:467] Master detector (slave(132)@67.195.138.9:59397) got new master pid: [email protected]:59397 I0920 08:27:38.182837 25396 slave.cpp:543] New master detected at [email protected]:59397 I0920 08:27:38.182848 25395 detector.cpp:467] Master detector ([email protected]:59397) got new master pid: [email protected]:59397 I0920 08:27:38.182982 25391 status_update_manager.cpp:157] New master detected at [email protected]:59397 W0920 08:27:38.183013 25392 master.cpp:1044] Ignoring register slave message from vesta.apache.org since not elected yet I0920 08:27:38.183235 25390 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:59397) found 1 registered masters I0920 08:27:38.183274 25392 master.cpp:644] Elected as master! I0920 08:27:38.183755 25390 detector.cpp:467] Master detector (scheduler(114)@67.195.138.9:59397) got new master pid: [email protected]:59397 I0920 08:27:38.183904 25390 sched.cpp:178] New master at [email protected]:59397 I0920 08:27:38.184101 25390 master.cpp:694] Registering framework 201309200827-160088899-59397-25371-0000 at scheduler(114)@67.195.138.9:59397 I0920 08:27:38.184195 25390 sched.cpp:237] Framework registered with 201309200827-160088899-59397-25371-0000 I0920 08:27:38.184206 25392 hierarchical_allocator_process.hpp:321] Added framework 201309200827-160088899-59397-25371-0000 I0920 08:27:38.184273 25390 sched.cpp:251] Scheduler::registered took 26.229us I0920 08:27:38.184329 25392 hierarchical_allocator_process.hpp:687] No resources available to allocate! I0920 08:27:38.184463 25392 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 134.522us I0920 08:27:39.123601 25391 hierarchical_allocator_process.hpp:687] No resources available to allocate! I0920 08:27:39.123704 25391 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 107.745us I0920 08:27:39.183827 25393 master.cpp:1075] Attempting to register slave on vesta.apache.org at slave(132)@67.195.138.9:59397 I0920 08:27:39.183904 25393 master.cpp:2165] Adding slave 201309200827-160088899-59397-25371-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] I0920 08:27:39.184087 25394 slave.cpp:603] Registered with master [email protected]:59397; given slave ID 201309200827-160088899-59397-25371-0 I0920 08:27:39.184171 25393 hierarchical_allocator_process.hpp:434] Added slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] available) I0920 08:27:39.184348 25393 hierarchical_allocator_process.hpp:712] Offering cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.184547 25393 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309200827-160088899-59397-25371-0 in 225.561us I0920 08:27:39.184617 25394 master.hpp:347] Adding offer 201309200827-160088899-59397-25371-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.184763 25394 master.cpp:1462] Sending 1 offers to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.185108 25390 sched.cpp:340] Scheduler::resourceOffers took 166.911us I0920 08:27:39.185264 25394 master.cpp:1699] Processing reply for offer 201309200827-160088899-59397-25371-0 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) for framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.185457 25394 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.185533 25394 master.cpp:1823] Launching task 0 of framework 201309200827-160088899-59397-25371-0000 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.185645 25390 slave.cpp:772] Got assigned task 0 for framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.185722 25394 master.hpp:357] Removing offer 201309200827-160088899-59397-25371-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.185822 25394 hierarchical_allocator_process.hpp:508] Framework 201309200827-160088899-59397-25371-0000 left cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave 201309200827-160088899-59397-25371-0 I0920 08:27:39.185891 25390 slave.cpp:883] Launching task 0 for framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.185987 25394 hierarchical_allocator_process.hpp:551] Framework 201309200827-160088899-59397-25371-0000 filtered slave 201309200827-160088899-59397-25371-0 for 5secs I0920 08:27:39.187417 25390 slave.cpp:994] Queuing task '0' for executor default of framework '201309200827-160088899-59397-25371-0000 I0920 08:27:39.187536 25390 slave.cpp:525] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_pUreiL/slaves/201309200827-160088899-59397-25371-0/frameworks/201309200827-160088899-59397-25371-0000/executors/default/runs/266abd6e-6ced-43b4-84f0-8754934bd0a7' I0920 08:27:39.188793 25393 exec.cpp:174] Executor started at: executor(41)@67.195.138.9:59397 with pid 25371 I0920 08:27:39.188938 25396 slave.cpp:1440] Got registration for executor 'default' of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.189296 25396 slave.cpp:1561] Flushing queued task 0 for executor 'default' of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.189312 25394 exec.cpp:198] Executor registered on slave 201309200827-160088899-59397-25371-0 I0920 08:27:39.190554 25394 exec.cpp:210] Executor::registered took 11.028us I0920 08:27:39.190666 25394 exec.cpp:285] Executor asked to run task '0' I0920 08:27:39.190784 25394 exec.cpp:294] Executor::launchTask took 39.485us I0920 08:27:39.191956 25394 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.192109 25390 slave.cpp:1773] Handling status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 from executor(41)@67.195.138.9:59397 I0920 08:27:39.192293 25395 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.192411 25395 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.192572 25395 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 to [email protected]:59397 I0920 08:27:39.192731 25390 master.cpp:1274] Status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 from slave(132)@67.195.138.9:59397 I0920 08:27:39.192821 25392 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.192845 25396 sched.cpp:395] Scheduler::statusUpdate took 37.243us I0920 08:27:39.192921 25392 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 to executor(41)@67.195.138.9:59397 I0920 08:27:39.193142 25391 exec.cpp:331] Executor received status update acknowledgement 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250 for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.193292 25391 master.cpp:481] Master terminating I0920 08:27:39.193337 25392 status_update_manager.cpp:375] Received status update acknowledgement (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.193457 25391 slave.cpp:1973] [email protected]:59397 exited W0920 08:27:39.193577 25391 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected I0920 08:27:39.193459 25371 master.cpp:206] Shutting down master I0920 08:27:39.193703 25391 slave.cpp:1386] Status update manager successfully handled status update acknowledgement (UUID: 90d7ccc1-fb23-4b11-b2ba-8168dbfa0250) for task 0 of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.193826 25371 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.194058 25371 master.cpp:237] Removing slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:39.194074 25393 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]) on slave 201309200827-160088899-59397-25371-0 from framework 201309200827-160088899-59397-25371-0000 I0920 08:27:39.195350 25394 master.cpp:265] Master started on 67.195.138.9:59397 I0920 08:27:39.195556 25394 master.cpp:280] Master ID: 201309200827-160088899-59397-25371 I0920 08:27:39.195755 25396 master.cpp:80] No whitelist given. Advertising offers for all slaves I0920 08:27:39.195953 25393 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : [email protected]:59397 I0920 08:27:39.236302 25397 detector.cpp:234] Master detector ([email protected]:59397) connected to ZooKeeper ... I0920 08:27:39.236413 25397 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0920 08:27:39.236522 25396 detector.cpp:420] Master detector (slave(132)@67.195.138.9:59397) found 0 registered masters I0920 08:27:39.236739 25391 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:59397) found 0 registered masters I0920 08:27:39.236835 25391 detector.cpp:441] Master detector (scheduler(114)@67.195.138.9:59397) couldn't find any masters I0920 08:27:39.236758 25396 detector.cpp:441] Master detector (slave(132)@67.195.138.9:59397) couldn't find any masters I0920 08:27:39.237120 25396 slave.cpp:577] Lost master(s) ... waiting I0920 08:27:39.236991 25391 sched.cpp:202] No master detected, waiting for another master I0920 08:27:39.237360 25391 sched.cpp:214] Scheduler::disconnected took 15.694us I0920 08:27:39.248180 25397 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0920 08:27:39.248505 25392 detector.cpp:420] Master detector (slave(132)@67.195.138.9:59397) found 1 registered masters I0920 08:27:39.248764 25395 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:59397) found 1 registered masters I0920 08:27:39.248889 25397 detector.cpp:420] Master detector ([email protected]:59397) found 1 registered masters I0920 08:27:39.249068 25392 detector.cpp:467] Master detector (slave(132)@67.195.138.9:59397) got new master pid: [email protected]:59397 I0920 08:27:39.249193 25392 slave.cpp:543] New master detected at [email protected]:59397 I0920 08:27:39.249310 25394 status_update_manager.cpp:157] New master detected at [email protected]:59397 W0920 08:27:39.249430 25396 master.cpp:1102] Ignoring re-register slave message from vesta.apache.org since not elected yet I0920 08:27:39.249709 25397 detector.cpp:467] Master detector ([email protected]:59397) got new master pid: [email protected]:59397 I0920 08:27:39.249927 25395 detector.cpp:467] Master detector (scheduler(114)@67.195.138.9:59397) got new master pid: [email protected]:59397 I0920 08:27:39.249936 25397 master.cpp:644] Elected as master! I0920 08:27:39.250097 25395 sched.cpp:178] New master at [email protected]:59397 I0920 08:27:40.184027 25397 master.cpp:1175] Attempting to re-register slave 201309200827-160088899-59397-25371-0 at slave(132)@67.195.138.9:59397 (vesta.apache.org) I0920 08:27:40.184125 25397 master.cpp:2165] Adding slave 201309200827-160088899-59397-25371-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] I0920 08:27:40.184329 25397 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:40.184356 25393 slave.cpp:644] Re-registered with master [email protected]:59397 W0920 08:27:40.184458 25397 master.cpp:2260] Possibly orphaned task 0 of framework 201309200827-160088899-59397-25371-0000 running on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:40.184980 25395 hierarchical_allocator_process.hpp:434] Added slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] available) I0920 08:27:40.185158 25395 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309200827-160088899-59397-25371-0 in 18.54us I0920 08:27:40.190084 25391 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201309200827-160088899-59397-25371-0000' I0920 08:27:40.197088 25395 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 18.519us I0920 08:27:40.251332 25396 master.cpp:736] Re-registering framework 201309200827-160088899-59397-25371-0000 at scheduler(114)@67.195.138.9:59397 I0920 08:27:40.251555 25393 sched.cpp:237] Framework registered with 201309200827-160088899-59397-25371-0000 I0920 08:27:40.251588 25396 slave.cpp:1332] Updating framework 201309200827-160088899-59397-25371-0000 pid to scheduler(114)@67.195.138.9:59397 I0920 08:27:40.251729 25392 hierarchical_allocator_process.hpp:321] Added framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.251765 25393 sched.cpp:251] Scheduler::registered took 96.858us I0920 08:27:40.251924 25392 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.252219 25392 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 329.693us I0920 08:27:40.252317 25391 master.hpp:347] Adding offer 201309200827-160088899-59397-25371-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:40.252437 25391 master.cpp:1462] Sending 1 offers to framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.252643 25397 sched.cpp:340] Scheduler::resourceOffers took 21.354us I0920 08:27:40.252846 25391 sched.cpp:498] Stopping framework '201309200827-160088899-59397-25371-0000' I0920 08:27:40.252873 25371 master.cpp:481] Master terminating I0920 08:27:40.253049 25371 master.cpp:206] Shutting down master I0920 08:27:40.253051 25396 slave.cpp:1973] [email protected]:59397 exited I0920 08:27:40.253216 25371 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) W0920 08:27:40.253252 25396 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected I0920 08:27:40.253525 25371 master.hpp:357] Removing offer 201309200827-160088899-59397-25371-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:40.253624 25391 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309200827-160088899-59397-25371-0 from framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.253855 25371 master.cpp:237] Removing slave 201309200827-160088899-59397-25371-0 (vesta.apache.org) I0920 08:27:40.254473 25371 slave.cpp:454] Slave terminating I0920 08:27:40.254554 25371 slave.cpp:1167] Asked to shut down framework 201309200827-160088899-59397-25371-0000 by @0.0.0.0:0 I0920 08:27:40.254642 25371 slave.cpp:1192] Shutting down framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.254708 25371 slave.cpp:2426] Shutting down executor 'default' of framework 201309200827-160088899-59397-25371-0000 I0920 08:27:40.254822 25397 exec.cpp:371] Executor asked to shutdown GMOCK WARNING: Uninteresting mock function call - returning directly. Function call: shutdown(0x2ada3002ba20) Stack trace: I0920 08:27:40.254905 25397 exec.cpp:386] Executor::shutdown took 20.077us [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2134 ms) I0920 08:27:40.291591 25371 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 35570 [----------] 2 tests from AllocatorZooKeeperTest/0 (4262 ms total) [----------] Global test environment tear-down [==========] 220 tests from 39 test cases ran. (176250 ms total) [ PASSED ] 219 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ZooKeeperTest.Create 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src' make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
