See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1488/>
------------------------------------------ [...truncated 14836 lines...] I0728 05:44:58.119472 17316 master.cpp:436] Master terminating I0728 05:44:58.120317 17316 master.cpp:209] Shutting down master I0728 05:44:58.119959 17339 exec.cpp:320] Executor received status update acknowledgement f050f64b-823a-466f-a577-ce22072ceecd for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.118978 17336 sched.cpp:395] Scheduler::statusUpdate took 34.783us I0728 05:44:58.120368 17340 slave.cpp:1912] [email protected]:45691 exited I0728 05:44:58.120868 17338 hierarchical_allocator_process.hpp:393] Deactivated framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.120868 17316 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 W0728 05:44:58.122252 17340 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0728 05:44:58.123352 17341 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000]) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.124055 17340 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by [email protected]:45691 I0728 05:44:58.125427 17340 slave.cpp:1165] Shutting down framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.126077 17342 master.cpp:230] Master started on 67.195.138.60:45691 I0728 05:44:58.126265 17340 slave.cpp:2356] Shutting down executor 'default' of framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.127122 17342 master.cpp:245] Master ID: 201307280544-1015726915-45691-17316 W0728 05:44:58.163836 17341 master.cpp:83] No whitelist given. Advertising offers for all slaves I0728 05:44:58.163324 17343 exec.cpp:360] Executor asked to shutdown I0728 05:44:58.192200 17343 exec.cpp:375] Executor::shutdown took 20.365us I0728 05:44:58.164079 17340 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:45691 I0728 05:44:58.163285 17337 status_update_manager.cpp:360] Received status update acknowledgement f050f64b-823a-466f-a577-ce22072ceecd for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.192776 17343 detector.cpp:234] Master detector ([email protected]:45691) connected to ZooKeeper ... I0728 05:44:58.193195 17338 detector.cpp:420] Master detector (scheduler(99)@67.195.138.60:45691) found 0 registered masters I0728 05:44:58.193378 17339 detector.cpp:420] Master detector (slave(108)@67.195.138.60:45691) found 0 registered masters I0728 05:44:58.193699 17336 slave.cpp:1371] Status update manager successfully handled status update acknowledgement f050f64b-823a-466f-a577-ce22072ceecd for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:58.194087 17343 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0728 05:44:58.194623 17338 detector.cpp:441] Master detector (scheduler(99)@67.195.138.60:45691) couldn't find any masters I0728 05:44:58.195896 17339 detector.cpp:441] Master detector (slave(108)@67.195.138.60:45691) couldn't find any masters I0728 05:44:58.198299 17339 slave.cpp:571] Lost master(s) ... waiting I0728 05:44:58.197794 17338 sched.cpp:202] No master detected, waiting for another master I0728 05:44:58.199478 17338 sched.cpp:214] Scheduler::disconnected took 21.398us I0728 05:44:58.219838 17343 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002' I0728 05:44:58.220118 17341 detector.cpp:420] Master detector (slave(108)@67.195.138.60:45691) found 1 registered masters I0728 05:44:58.220937 17340 detector.cpp:420] Master detector (scheduler(99)@67.195.138.60:45691) found 1 registered masters I0728 05:44:58.221107 17343 detector.cpp:420] Master detector ([email protected]:45691) found 1 registered masters I0728 05:44:58.221109 17341 detector.cpp:467] Master detector (slave(108)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:44:58.221870 17340 detector.cpp:467] Master detector (scheduler(99)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:44:58.222475 17340 sched.cpp:178] New master at [email protected]:45691 W0728 05:44:58.222924 17337 master.cpp:655] Ignoring re-register framework message since not elected yet I0728 05:44:58.222036 17341 slave.cpp:537] New master detected at [email protected]:45691 I0728 05:44:58.222180 17343 detector.cpp:467] Master detector ([email protected]:45691) got new master pid: [email protected]:45691 I0728 05:44:58.224350 17343 master.cpp:583] Elected as master! I0728 05:44:58.223870 17340 status_update_manager.cpp:155] New master detected at [email protected]:45691 I0728 05:44:59.193323 17341 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0728 05:44:59.216574 17341 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 23.253721ms I0728 05:44:59.223507 17336 master.cpp:675] Re-registering framework 201307280544-1015726915-45691-17316-0000 at scheduler(99)@67.195.138.60:45691 I0728 05:44:59.223686 17339 sched.cpp:268] Framework re-registered with 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.223748 17336 hierarchical_allocator_process.hpp:317] Added framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.223925 17339 sched.cpp:282] Scheduler::reregistered took 21.764us I0728 05:44:59.224382 17336 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0728 05:44:59.224658 17343 master.cpp:1034] Attempting to re-register slave 201307280544-1015726915-45691-17316-0 at slave(108)@67.195.138.60:45691 (janus.apache.org) I0728 05:44:59.225858 17343 master.cpp:1941] Adding slave 201307280544-1015726915-45691-17316-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] I0728 05:44:59.225378 17336 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 996.113us I0728 05:44:59.227450 17341 slave.cpp:638] Re-registered with master [email protected]:45691 I0728 05:44:59.227496 17343 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 W0728 05:44:59.229053 17340 slave.cpp:1301] Ignoring updating pid for framework 201307280544-1015726915-45691-17316-0000 because it is terminating I0728 05:44:59.229156 17341 hierarchical_allocator_process.hpp:430] Added slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] available) I0728 05:44:59.229972 17341 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 to framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.230564 17341 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307280544-1015726915-45691-17316-0 in 623.059us I0728 05:44:59.230679 17336 master.hpp:328] Adding offer 201307280544-1015726915-45691-17316-0 with resources cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:44:59.231549 17336 master.cpp:1322] Sending 1 offers to framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.232151 17337 sched.cpp:340] Scheduler::resourceOffers took 27.499us I0728 05:44:59.232381 17316 master.cpp:436] Master terminating I0728 05:44:59.233074 17316 master.cpp:209] Shutting down master I0728 05:44:59.233075 17338 slave.cpp:493] Slave asked to shut down by [email protected]:45691 I0728 05:44:59.232569 17337 sched.cpp:498] Stopping framework '201307280544-1015726915-45691-17316-0000' I0728 05:44:59.233608 17339 hierarchical_allocator_process.hpp:393] Deactivated framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.233619 17316 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 I0728 05:44:59.234127 17338 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by [email protected]:45691 I0728 05:44:59.235725 17316 master.hpp:338] Removing offer 201307280544-1015726915-45691-17316-0 with resources cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:44:59.235848 17337 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.258060 17337 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000]) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.258406 17337 hierarchical_allocator_process.hpp:348] Removed framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.258905 17337 hierarchical_allocator_process.hpp:456] Removed slave 201307280544-1015726915-45691-17316-0 W0728 05:44:59.236143 17338 slave.cpp:1161] Ignoring shutdown framework 201307280544-1015726915-45691-17316-0000 because it is terminating I0728 05:44:59.259853 17338 slave.cpp:448] Slave terminating I0728 05:44:59.260316 17338 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by @0.0.0.0:0 W0728 05:44:59.260788 17338 slave.cpp:1161] Ignoring shutdown framework 201307280544-1015726915-45691-17316-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2303 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I0728 05:44:59.272364 17342 master.cpp:230] Master started on 67.195.138.60:45691 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@662: Client environment:host.name=janus 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@671: Client environment:os.version=#60-Ubuntu SMP Wed Feb 13 13:22:43 UTC 2013 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src> 2013-07-28 05:44:59,273:17316(0x2b6dacb1e700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:48100 sessionTimeout=10000 watcher=0x2b6daa41ca90 sessionId=0 sessionPasswd=<null> context=0x2b6dc8037310 flags=0 I0728 05:44:59.272443 17342 master.cpp:245] Master ID: 201307280544-1015726915-45691-17316 I0728 05:44:59.273193 17341 slave.cpp:113] Slave started on 109)@67.195.138.60:45691 W0728 05:44:59.282737 17340 master.cpp:83] No whitelist given. Advertising offers for all slaves I0728 05:44:59.282784 17343 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:45691 I0728 05:44:59.283093 17341 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] I0728 05:44:59.285056 17338 slave.cpp:398] Finished recovery I0728 05:44:59.308892 17342 detector.cpp:234] Master detector ([email protected]:45691) connected to ZooKeeper ... I0728 05:44:59.308944 17342 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0728 05:44:59.309521 17336 detector.cpp:234] Master detector (slave(109)@67.195.138.60:45691) connected to ZooKeeper ... I0728 05:44:59.309754 17336 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0728 05:44:59.310179 17341 detector.cpp:234] Master detector (scheduler(100)@67.195.138.60:45691) connected to ZooKeeper ... I0728 05:44:59.311112 17341 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0728 05:44:59.318940 17342 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I0728 05:44:59.319232 17336 detector.cpp:420] Master detector (slave(109)@67.195.138.60:45691) found 1 registered masters I0728 05:44:59.319757 17342 detector.cpp:420] Master detector ([email protected]:45691) found 1 registered masters I0728 05:44:59.320058 17336 detector.cpp:467] Master detector (slave(109)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:44:59.320350 17340 slave.cpp:537] New master detected at [email protected]:45691 I0728 05:44:59.320785 17337 status_update_manager.cpp:155] New master detected at [email protected]:45691 I0728 05:44:59.320597 17341 detector.cpp:420] Master detector (scheduler(100)@67.195.138.60:45691) found 1 registered masters W0728 05:44:59.320843 17338 master.cpp:943] Ignoring register slave message from janus.apache.org since not elected yet I0728 05:44:59.320596 17342 detector.cpp:467] Master detector ([email protected]:45691) got new master pid: [email protected]:45691 I0728 05:44:59.322540 17341 detector.cpp:467] Master detector (scheduler(100)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:44:59.323194 17341 sched.cpp:178] New master at [email protected]:45691 I0728 05:44:59.322808 17342 master.cpp:583] Elected as master! I0728 05:44:59.324209 17342 master.cpp:633] Registering framework 201307280544-1015726915-45691-17316-0000 at scheduler(100)@67.195.138.60:45691 I0728 05:44:59.324656 17336 sched.cpp:237] Framework registered with 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.324666 17343 hierarchical_allocator_process.hpp:317] Added framework 201307280544-1015726915-45691-17316-0000 I0728 05:44:59.325546 17343 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0728 05:44:59.326009 17343 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 464.705us I0728 05:44:59.325063 17336 sched.cpp:251] Scheduler::registered took 17.309us I0728 05:45:00.284916 17342 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0728 05:45:00.307739 17342 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 22.828184ms I0728 05:45:00.321135 17338 master.cpp:962] Attempting to register slave on janus.apache.org at slave(109)@67.195.138.60:45691 I0728 05:45:00.321182 17338 master.cpp:1941] Adding slave 201307280544-1015726915-45691-17316-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] I0728 05:45:00.321581 17343 slave.cpp:597] Registered with master [email protected]:45691; given slave ID 201307280544-1015726915-45691-17316-0 I0728 05:45:00.321811 17336 hierarchical_allocator_process.hpp:430] Added slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] available) I0728 05:45:00.322587 17336 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 to framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.323184 17336 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307280544-1015726915-45691-17316-0 in 638.67us I0728 05:45:00.323253 17343 master.hpp:328] Adding offer 201307280544-1015726915-45691-17316-0 with resources cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.324121 17343 master.cpp:1322] Sending 1 offers to framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.324905 17338 sched.cpp:340] Scheduler::resourceOffers took 213.927us I0728 05:45:00.325217 17343 master.cpp:1555] Processing reply for offer 201307280544-1015726915-45691-17316-0 on slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) for framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.325727 17343 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.326180 17343 master.cpp:1674] Launching task 0 of framework 201307280544-1015726915-45691-17316-0000 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) I0728 05:45:00.326782 17338 slave.cpp:747] Got assigned task 0 for framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.326864 17340 hierarchical_allocator_process.hpp:505] Framework 201307280544-1015726915-45691-17316-0000 left cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] unused on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.327848 17340 hierarchical_allocator_process.hpp:548] Framework 201307280544-1015726915-45691-17316-0000 filtered slave 201307280544-1015726915-45691-17316-0 for 5secs I0728 05:45:00.327425 17338 slave.cpp:845] Launching task 0 for framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.326877 17343 master.hpp:338] Removing offer 201307280544-1015726915-45691-17316-0 with resources cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.330775 17338 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_DoqZWZ/slaves/201307280544-1015726915-45691-17316-0/frameworks/201307280544-1015726915-45691-17316-0000/executors/default/runs/1dfee07a-98ff-432c-9f70-40ea819f151f' I0728 05:45:00.331060 17338 slave.cpp:956] Queuing task '0' for executor default of framework '201307280544-1015726915-45691-17316-0000 I0728 05:45:00.331217 17339 exec.cpp:171] Executor started at: executor(39)@67.195.138.60:45691 with pid 17316 I0728 05:45:00.331303 17338 slave.cpp:519] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_DoqZWZ/slaves/201307280544-1015726915-45691-17316-0/frameworks/201307280544-1015726915-45691-17316-0000/executors/default/runs/1dfee07a-98ff-432c-9f70-40ea819f151f' I0728 05:45:00.332324 17338 slave.cpp:1423] Got registration for executor 'default' of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.332869 17338 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.332891 17340 exec.cpp:195] Executor registered on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.333793 17340 exec.cpp:206] Executor::registered took 19.775us I0728 05:45:00.334322 17340 exec.cpp:274] Executor asked to run task '0' I0728 05:45:00.334836 17340 exec.cpp:283] Executor::launchTask took 31.715us I0728 05:45:00.336877 17340 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.336987 17340 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 from executor(39)@67.195.138.60:45691 I0728 05:45:00.337390 17339 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 with checkpoint=false I0728 05:45:00.337898 17339 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.338457 17339 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 to [email protected]:45691 I0728 05:45:00.338913 17341 master.cpp:1093] Status update from slave(109)@67.195.138.60:45691: task 0 of framework 201307280544-1015726915-45691-17316-0000 is now in state TASK_RUNNING I0728 05:45:00.339007 17337 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.340046 17337 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: 00066b3f-5c77-4129-8765-2a37c367d809) for task 0 of framework 201307280544-1015726915-45691-17316-0000 to executor(39)@67.195.138.60:45691 I0728 05:45:00.339897 17343 master.cpp:436] Master terminating I0728 05:45:00.339459 17342 sched.cpp:395] Scheduler::statusUpdate took 28.798us I0728 05:45:00.340670 17338 exec.cpp:320] Executor received status update acknowledgement 00066b3f-5c77-4129-8765-2a37c367d809 for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.341125 17337 slave.cpp:1912] [email protected]:45691 exited W0728 05:45:00.342507 17337 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0728 05:45:00.341195 17316 master.cpp:209] Shutting down master I0728 05:45:00.344279 17343 hierarchical_allocator_process.hpp:393] Deactivated framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.344283 17316 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:00.344306 17341 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by [email protected]:45691 I0728 05:45:00.343200 17337 status_update_manager.cpp:360] Received status update acknowledgement 00066b3f-5c77-4129-8765-2a37c367d809 for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.345221 17339 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000]) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.345587 17341 slave.cpp:1165] Shutting down framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.347095 17341 slave.cpp:2356] Shutting down executor 'default' of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.347482 17336 master.cpp:230] Master started on 67.195.138.60:45691 I0728 05:45:00.347633 17341 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 00066b3f-5c77-4129-8765-2a37c367d809 for task 0 of framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:00.347728 17343 exec.cpp:360] Executor asked to shutdown I0728 05:45:00.348114 17336 master.cpp:245] Master ID: 201307280545-1015726915-45691-17316 I0728 05:45:00.365857 17343 exec.cpp:375] Executor::shutdown took 21.519us W0728 05:45:00.366547 17342 master.cpp:83] No whitelist given. Advertising offers for all slaves I0728 05:45:00.366672 17339 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:45691 I0728 05:45:00.404041 17342 detector.cpp:234] Master detector ([email protected]:45691) connected to ZooKeeper ... I0728 05:45:00.404083 17342 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I0728 05:45:00.404409 17337 detector.cpp:420] Master detector (slave(109)@67.195.138.60:45691) found 0 registered masters I0728 05:45:00.404744 17343 detector.cpp:420] Master detector (scheduler(100)@67.195.138.60:45691) found 0 registered masters I0728 05:45:00.405397 17343 detector.cpp:441] Master detector (scheduler(100)@67.195.138.60:45691) couldn't find any masters I0728 05:45:00.404880 17337 detector.cpp:441] Master detector (slave(109)@67.195.138.60:45691) couldn't find any masters I0728 05:45:00.406323 17337 slave.cpp:571] Lost master(s) ... waiting I0728 05:45:00.405880 17340 sched.cpp:202] No master detected, waiting for another master I0728 05:45:00.415794 17340 sched.cpp:214] Scheduler::disconnected took 13.121us I0728 05:45:00.418489 17342 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I0728 05:45:00.419067 17339 detector.cpp:420] Master detector (slave(109)@67.195.138.60:45691) found 1 registered masters I0728 05:45:00.419296 17336 detector.cpp:420] Master detector (scheduler(100)@67.195.138.60:45691) found 1 registered masters I0728 05:45:00.419723 17342 detector.cpp:420] Master detector ([email protected]:45691) found 1 registered masters I0728 05:45:00.419936 17339 detector.cpp:467] Master detector (slave(109)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:45:00.420152 17336 detector.cpp:467] Master detector (scheduler(100)@67.195.138.60:45691) got new master pid: [email protected]:45691 I0728 05:45:00.420951 17336 sched.cpp:178] New master at [email protected]:45691 I0728 05:45:00.420529 17339 slave.cpp:537] New master detected at [email protected]:45691 I0728 05:45:00.421900 17336 status_update_manager.cpp:155] New master detected at [email protected]:45691 I0728 05:45:00.420513 17342 detector.cpp:467] Master detector ([email protected]:45691) got new master pid: [email protected]:45691 W0728 05:45:00.422044 17340 master.cpp:989] Ignoring re-register slave message from janus.apache.org since not elected yet I0728 05:45:00.423879 17340 master.cpp:583] Elected as master! I0728 05:45:01.321488 17337 master.cpp:1034] Attempting to re-register slave 201307280544-1015726915-45691-17316-0 at slave(109)@67.195.138.60:45691 (janus.apache.org) I0728 05:45:01.340499 17337 master.cpp:1941] Adding slave 201307280544-1015726915-45691-17316-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] I0728 05:45:01.340950 17343 slave.cpp:638] Re-registered with master [email protected]:45691 I0728 05:45:01.340996 17337 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 W0728 05:45:01.341775 17337 master.cpp:2033] Possibly orphaned task 0 of framework 201307280544-1015726915-45691-17316-0000 running on slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) I0728 05:45:01.342510 17343 hierarchical_allocator_process.hpp:430] Added slave 201307280544-1015726915-45691-17316-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] available) I0728 05:45:01.342777 17343 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307280544-1015726915-45691-17316-0 in 11.451us I0728 05:45:01.368512 17336 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 17.837us I0728 05:45:01.421664 17339 master.cpp:675] Re-registering framework 201307280544-1015726915-45691-17316-0000 at scheduler(100)@67.195.138.60:45691 I0728 05:45:01.421844 17341 sched.cpp:268] Framework re-registered with 201307280544-1015726915-45691-17316-0000 W0728 05:45:01.421901 17338 slave.cpp:1301] Ignoring updating pid for framework 201307280544-1015726915-45691-17316-0000 because it is terminating I0728 05:45:01.421964 17343 hierarchical_allocator_process.hpp:317] Added framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.422099 17341 sched.cpp:282] Scheduler::reregistered took 16.829us I0728 05:45:01.423014 17343 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 to framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.424069 17343 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 1.097175ms I0728 05:45:01.424132 17337 master.hpp:328] Adding offer 201307280545-1015726915-45691-17316-0 with resources cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:01.424934 17337 master.cpp:1322] Sending 1 offers to framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.425537 17339 sched.cpp:340] Scheduler::resourceOffers took 29.134us I0728 05:45:01.425782 17316 master.cpp:436] Master terminating I0728 05:45:01.425896 17339 sched.cpp:498] Stopping framework '201307280544-1015726915-45691-17316-0000' I0728 05:45:01.426421 17316 master.cpp:209] Shutting down master I0728 05:45:01.427431 17336 hierarchical_allocator_process.hpp:393] Deactivated framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.426444 17337 slave.cpp:493] Slave asked to shut down by [email protected]:45691 I0728 05:45:01.427445 17316 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:01.432160 17316 master.hpp:338] Removing offer 201307280545-1015726915-45691-17316-0 with resources cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] on slave 201307280544-1015726915-45691-17316-0 I0728 05:45:01.431591 17337 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by [email protected]:45691 I0728 05:45:01.432206 17336 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.433585 17336 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):21353; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):21353; ports(*):[31000-32000]) on slave 201307280544-1015726915-45691-17316-0 from framework 201307280544-1015726915-45691-17316-0000 W0728 05:45:01.432967 17337 slave.cpp:1161] Ignoring shutdown framework 201307280544-1015726915-45691-17316-0000 because it is terminating I0728 05:45:01.434412 17336 hierarchical_allocator_process.hpp:348] Removed framework 201307280544-1015726915-45691-17316-0000 I0728 05:45:01.435333 17336 hierarchical_allocator_process.hpp:456] Removed slave 201307280544-1015726915-45691-17316-0 I0728 05:45:01.434836 17337 slave.cpp:448] Slave terminating I0728 05:45:01.436242 17337 slave.cpp:1140] Asked to shut down framework 201307280544-1015726915-45691-17316-0000 by @0.0.0.0:0 W0728 05:45:01.436715 17337 slave.cpp:1161] Ignoring shutdown framework 201307280544-1015726915-45691-17316-0000 because it is terminating [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2172 ms) I0728 05:45:01.461766 17316 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 48100 [----------] 2 tests from AllocatorZooKeeperTest/0 (4475 ms total) [----------] Global test environment tear-down [==========] 208 tests from 38 test cases ran. (99917 ms total) [ PASSED ] 207 tests. [ FAILED ] 1 test, listed below: [ FAILED ] AllocatorTest/0.FrameworkExited, 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 `<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
