You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/10/07 21:50:06 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1698
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1698/changes>
Changes:
[vinod] Added slave recovery doc.
------------------------------------------
[...truncated 16797 lines...]
I1007 19:50:02.267879 26005 master.cpp:1272] Status update TASK_RUNNING (UUID: df011696-80e4-48b4-bb37-d1eabb32828f) for task 0 of framework 201310071950-160088899-53996-25983-0000 from slave(133)@67.195.138.9:53996
I1007 19:50:02.275763 26005 master.cpp:485] Master terminating
I1007 19:50:02.267957 26008 sched.cpp:395] Scheduler::statusUpdate took 14.887us
I1007 19:50:02.267911 26006 slave.cpp:1899] Status update manager successfully handled status update TASK_RUNNING (UUID: df011696-80e4-48b4-bb37-d1eabb32828f) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.283771 25983 master.cpp:206] Shutting down master
I1007 19:50:02.307603 25983 master.hpp:333] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:02.299605 26006 slave.cpp:1905] Sending acknowledgement for status update TASK_RUNNING (UUID: df011696-80e4-48b4-bb37-d1eabb32828f) for task 0 of framework 201310071950-160088899-53996-25983-0000 to executor(40)@67.195.138.9:53996
I1007 19:50:02.323521 26006 slave.cpp:1980] master@67.195.138.9:53996 exited
I1007 19:50:02.315667 26009 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]) on slave 201310071950-160088899-53996-25983-0 from framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.323554 26010 exec.cpp:331] Executor received status update acknowledgement df011696-80e4-48b4-bb37-d1eabb32828f for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.315608 25983 master.cpp:237] Removing slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
W1007 19:50:02.331456 26006 slave.cpp:1983] Master disconnected! Waiting for a new master to be elected
I1007 19:50:02.356360 26007 master.cpp:269] Master started on 67.195.138.9:53996
I1007 19:50:02.419075 26007 master.cpp:284] Master ID: 201310071950-160088899-53996-25983
I1007 19:50:02.427230 26007 master.cpp:80] No whitelist given. Advertising offers for all slaves
I1007 19:50:02.409657 26010 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:02.409833 26009 detector.cpp:420] Master detector (slave(133)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:02.450829 26009 detector.cpp:441] Master detector (slave(133)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:02.458801 26009 slave.cpp:581] Lost master(s) ... waiting
I1007 19:50:02.409418 26003 detector.cpp:234] Master detector (master@67.195.138.9:53996) connected to ZooKeeper ...
I1007 19:50:02.474686 26003 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I1007 19:50:02.363407 26005 status_update_manager.cpp:375] Received status update acknowledgement (UUID: df011696-80e4-48b4-bb37-d1eabb32828f) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.490718 26005 slave.cpp:1393] Status update manager successfully handled status update acknowledgement (UUID: df011696-80e4-48b4-bb37-d1eabb32828f) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.442858 26010 detector.cpp:441] Master detector (scheduler(114)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:02.427310 26008 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.9:53996
I1007 19:50:02.496906 26003 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I1007 19:50:02.497153 26009 detector.cpp:420] Master detector (slave(133)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:02.518674 26007 sched.cpp:202] No master detected, waiting for another master
I1007 19:50:02.550299 26007 sched.cpp:214] Scheduler::disconnected took 11.202us
I1007 19:50:02.534988 26003 detector.cpp:420] Master detector (master@67.195.138.9:53996) found 1 registered masters
I1007 19:50:02.542821 26009 detector.cpp:467] Master detector (slave(133)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:02.519004 26010 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:02.566769 26003 detector.cpp:467] Master detector (master@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:02.574278 26009 slave.cpp:547] New master detected at master@67.195.138.9:53996
I1007 19:50:02.598094 26008 status_update_manager.cpp:157] New master detected at master@67.195.138.9:53996
I1007 19:50:02.590153 26003 master.cpp:639] Elected as master!
I1007 19:50:02.582602 26010 detector.cpp:467] Master detector (scheduler(114)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:02.621984 26010 sched.cpp:178] New master at master@67.195.138.9:53996
I1007 19:50:02.629993 26006 master.cpp:731] Re-registering framework 201310071950-160088899-53996-25983-0000 at scheduler(114)@67.195.138.9:53996
I1007 19:50:02.637964 26006 sched.cpp:237] Framework registered with 201310071950-160088899-53996-25983-0000
I1007 19:50:02.645818 26006 sched.cpp:251] Scheduler::registered took 9.939us
I1007 19:50:02.638078 26010 hierarchical_allocator_process.hpp:332] Added framework 201310071950-160088899-53996-25983-0000
I1007 19:50:02.661720 26010 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I1007 19:50:02.669675 26010 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7.957217ms
I1007 19:50:02.965914 26009 master.cpp:1173] Attempting to re-register slave 201310071950-160088899-53996-25983-0 at slave(133)@67.195.138.9:53996 (vesta.apache.org)
I1007 19:50:02.966025 26009 master.cpp:2170] Adding slave 201310071950-160088899-53996-25983-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I1007 19:50:02.972111 26005 slave.cpp:648] Re-registered with master master@67.195.138.9:53996
I1007 19:50:02.972157 26009 master.hpp:315] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:02.988160 26009 slave.cpp:1339] Updating framework 201310071950-160088899-53996-25983-0000 pid to scheduler(114)@67.195.138.9:53996
I1007 19:50:02.988237 26005 hierarchical_allocator_process.hpp:445] Added slave 201310071950-160088899-53996-25983-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)
I1007 19:50:03.003947 26005 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.011956 26005 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310071950-160088899-53996-25983-0 in 8.04226ms
I1007 19:50:03.012045 26004 master.hpp:343] Adding offer 201310071950-160088899-53996-25983-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.027823 26004 master.cpp:1460] Sending 1 offers to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.035886 26007 sched.cpp:340] Scheduler::resourceOffers took 30.236us
I1007 19:50:03.036106 25983 master.cpp:485] Master terminating
I1007 19:50:03.043776 26007 sched.cpp:498] Stopping framework '201310071950-160088899-53996-25983-0000'
I1007 19:50:03.051762 26004 slave.cpp:1980] master@67.195.138.9:53996 exited
W1007 19:50:03.067677 26004 slave.cpp:1983] Master disconnected! Waiting for a new master to be elected
I1007 19:50:03.051780 25983 master.cpp:206] Shutting down master
I1007 19:50:03.095669 25983 master.hpp:333] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.103698 25983 master.hpp:353] Removing offer 201310071950-160088899-53996-25983-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.103793 26003 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201310071950-160088899-53996-25983-0 from framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.111698 25983 master.cpp:237] Removing slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.128053 26004 slave.cpp:454] Slave terminating
I1007 19:50:03.137709 26010 detector.cpp:420] Master detector (slave(133)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:03.151469 26010 detector.cpp:441] Master detector (slave(133)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:03.143210 26004 slave.cpp:1171] Asked to shut down framework 201310071950-160088899-53996-25983-0000 by @0.0.0.0:0
I1007 19:50:03.167376 26004 slave.cpp:1196] Shutting down framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.171211 26006 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201310071950-160088899-53996-25983-0000'
I1007 19:50:03.175369 26004 slave.cpp:2433] Shutting down executor 'default' of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.191349 26004 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2b63b801c760)
Stack trace:
I1007 19:50:03.199303 26004 exec.cpp:386] Executor::shutdown took 24.525us
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1536 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@679: Client environment:user.name=(null)
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@log_env@699: Client environment:user.dir=/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:52020 sessionTimeout=10000 watcher=0x2b63b0cadaa0 sessionId=0 sessionPasswd=<null> context=0x2b63dc0251d0 flags=0
2013-10-07 19:50:03,213:25983(0x2b63b39f2700):ZOO_DEBUG@start_threads@152: starting threads...
2013-10-07 19:50:03,213:25983(0x2b64c5175700):ZOO_DEBUG@do_completion@326: started completion thread
2013-10-07 19:50:03,213:25983(0x2b64c4d73700):ZOO_DEBUG@do_io@279: started IO thread
I1007 19:50:03.211932 26008 master.cpp:269] Master started on 67.195.138.9:53996
I1007 19:50:03.234731 26008 master.cpp:284] Master ID: 201310071950-160088899-53996-25983
I1007 19:50:03.221308 26007 detector.cpp:234] Master detector (master@67.195.138.9:53996) connected to ZooKeeper ...
I1007 19:50:03.229030 26010 detector.cpp:234] Master detector (slave(134)@67.195.138.9:53996) connected to ZooKeeper ...
I1007 19:50:03.229508 26004 detector.cpp:234] Master detector (scheduler(115)@67.195.138.9:53996) connected to ZooKeeper ...
I1007 19:50:03.213076 26003 slave.cpp:108] Slave started on 134)@67.195.138.9:53996
I1007 19:50:03.243257 26009 master.cpp:80] No whitelist given. Advertising offers for all slaves
I1007 19:50:03.243263 26006 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.9:53996
I1007 19:50:03.251124 26007 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I1007 19:50:03.259104 26010 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I1007 19:50:03.267086 26004 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I1007 19:50:03.275133 26003 slave.cpp:208] Slave resources: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I1007 19:50:03.308353 26007 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I1007 19:50:03.315129 26010 detector.cpp:420] Master detector (slave(134)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.323807 26004 detector.cpp:420] Master detector (scheduler(115)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.331406 26009 slave.cpp:399] Finished recovery
I1007 19:50:03.339427 26007 detector.cpp:420] Master detector (master@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.347275 26010 detector.cpp:467] Master detector (slave(134)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.355244 26004 detector.cpp:467] Master detector (scheduler(115)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.371229 26007 detector.cpp:467] Master detector (master@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.378772 26010 slave.cpp:547] New master detected at master@67.195.138.9:53996
I1007 19:50:03.386741 26004 sched.cpp:178] New master at master@67.195.138.9:53996
I1007 19:50:03.394704 26007 master.cpp:639] Elected as master!
I1007 19:50:03.418628 26007 master.cpp:1072] Attempting to register slave on vesta.apache.org at slave(134)@67.195.138.9:53996
I1007 19:50:03.402688 26010 status_update_manager.cpp:157] New master detected at master@67.195.138.9:53996
I1007 19:50:03.426592 26007 master.cpp:2170] Adding slave 201310071950-160088899-53996-25983-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I1007 19:50:03.453310 26003 slave.cpp:607] Registered with master master@67.195.138.9:53996; given slave ID 201310071950-160088899-53996-25983-0
I1007 19:50:03.453397 26007 master.cpp:689] Registering framework 201310071950-160088899-53996-25983-0000 at scheduler(115)@67.195.138.9:53996
I1007 19:50:03.453420 26008 hierarchical_allocator_process.hpp:445] Added slave 201310071950-160088899-53996-25983-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)
I1007 19:50:03.469193 26003 sched.cpp:237] Framework registered with 201310071950-160088899-53996-25983-0000
I1007 19:50:03.477150 26008 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310071950-160088899-53996-25983-0 in 8.92us
I1007 19:50:03.485093 26003 sched.cpp:251] Scheduler::registered took 13.785us
I1007 19:50:03.493062 26008 hierarchical_allocator_process.hpp:332] Added framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.508955 26008 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.516993 26008 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 8.069298ms
I1007 19:50:03.517058 26007 master.hpp:343] Adding offer 201310071950-160088899-53996-25983-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.532826 26007 master.cpp:1460] Sending 1 offers to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.541064 26005 sched.cpp:340] Scheduler::resourceOffers took 192.539us
I1007 19:50:03.548869 26009 master.cpp:1697] Processing reply for offer 201310071950-160088899-53996-25983-0 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org) for framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.556767 26009 master.hpp:315] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.564643 26009 master.cpp:1821] Launching task 0 of framework 201310071950-160088899-53996-25983-0000 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.572696 26009 master.hpp:353] Removing offer 201310071950-160088899-53996-25983-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.572751 26005 slave.cpp:776] Got assigned task 0 for framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.572762 26007 hierarchical_allocator_process.hpp:547] Framework 201310071950-160088899-53996-25983-0000 left cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave 201310071950-160088899-53996-25983-0
I1007 19:50:03.588719 26005 slave.cpp:887] Launching task 0 for framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.596550 26007 hierarchical_allocator_process.hpp:590] Framework 201310071950-160088899-53996-25983-0000 filtered slave 201310071950-160088899-53996-25983-0 for 5secs
I1007 19:50:03.606385 26005 slave.cpp:998] Queuing task '0' for executor default of framework '201310071950-160088899-53996-25983-0000
I1007 19:50:03.607697 26009 exec.cpp:174] Executor started at: executor(41)@67.195.138.9:53996 with pid 25983
I1007 19:50:03.620371 26005 slave.cpp:529] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_XHwW07/slaves/201310071950-160088899-53996-25983-0/frameworks/201310071950-160088899-53996-25983-0000/executors/default/runs/d29a2db0-2b96-491b-82ea-4f00931d5ff0'
I1007 19:50:03.636322 26005 slave.cpp:1447] Got registration for executor 'default' of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.648342 26005 slave.cpp:1568] Flushing queued task 0 for executor 'default' of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.648347 26003 exec.cpp:198] Executor registered on slave 201310071950-160088899-53996-25983-0
I1007 19:50:03.657331 26003 exec.cpp:210] Executor::registered took 15.467us
I1007 19:50:03.660182 26003 exec.cpp:285] Executor asked to run task '0'
I1007 19:50:03.664181 26003 exec.cpp:294] Executor::launchTask took 30.728us
I1007 19:50:03.669252 26003 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.672154 26003 slave.cpp:1780] Handling status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000 from executor(41)@67.195.138.9:53996
I1007 19:50:03.676142 26003 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.680053 26003 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.684082 26003 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000 to master@67.195.138.9:53996
I1007 19:50:03.688117 26003 slave.cpp:1899] Status update manager successfully handled status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.688117 26010 master.cpp:1272] Status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000 from slave(134)@67.195.138.9:53996
I1007 19:50:03.688186 26007 sched.cpp:395] Scheduler::statusUpdate took 23.736us
I1007 19:50:03.692008 26003 slave.cpp:1905] Sending acknowledgement for status update TASK_RUNNING (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000 to executor(41)@67.195.138.9:53996
I1007 19:50:03.696004 26010 master.cpp:485] Master terminating
I1007 19:50:03.704016 26005 exec.cpp:331] Executor received status update acknowledgement 26cbb133-424f-4aa7-82c7-30283ca04147 for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.704053 26003 status_update_manager.cpp:375] Received status update acknowledgement (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.707948 26010 slave.cpp:1980] master@67.195.138.9:53996 exited
I1007 19:50:03.707980 25983 master.cpp:206] Shutting down master
W1007 19:50:03.719859 26010 slave.cpp:1983] Master disconnected! Waiting for a new master to be elected
I1007 19:50:03.723845 25983 master.hpp:333] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.727815 26010 slave.cpp:1393] Status update manager successfully handled status update acknowledgement (UUID: 26cbb133-424f-4aa7-82c7-30283ca04147) for task 0 of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.731848 25983 master.cpp:237] Removing slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.731891 26009 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]) on slave 201310071950-160088899-53996-25983-0 from framework 201310071950-160088899-53996-25983-0000
I1007 19:50:03.744398 26008 master.cpp:269] Master started on 67.195.138.9:53996
I1007 19:50:03.747783 26008 master.cpp:284] Master ID: 201310071950-160088899-53996-25983
I1007 19:50:03.763815 26010 master.cpp:80] No whitelist given. Advertising offers for all slaves
I1007 19:50:03.763919 26006 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.9:53996
I1007 19:50:03.770283 26007 detector.cpp:234] Master detector (master@67.195.138.9:53996) connected to ZooKeeper ...
I1007 19:50:03.770500 26009 detector.cpp:420] Master detector (scheduler(115)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:03.770648 26004 detector.cpp:420] Master detector (slave(134)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:03.791491 26004 detector.cpp:441] Master detector (slave(134)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:03.787523 26009 detector.cpp:441] Master detector (scheduler(115)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:03.783540 26007 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I1007 19:50:03.795495 26004 slave.cpp:581] Lost master(s) ... waiting
I1007 19:50:03.799473 26009 sched.cpp:202] No master detected, waiting for another master
I1007 19:50:03.813839 26007 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I1007 19:50:03.814141 26010 detector.cpp:420] Master detector (slave(134)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.814311 26003 detector.cpp:420] Master detector (scheduler(115)@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.819375 26009 sched.cpp:214] Scheduler::disconnected took 10.453us
I1007 19:50:03.823807 26007 detector.cpp:420] Master detector (master@67.195.138.9:53996) found 1 registered masters
I1007 19:50:03.827782 26010 detector.cpp:467] Master detector (slave(134)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.831784 26003 detector.cpp:467] Master detector (scheduler(115)@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.839694 26007 detector.cpp:467] Master detector (master@67.195.138.9:53996) got new master pid: master@67.195.138.9:53996
I1007 19:50:03.851233 26007 master.cpp:639] Elected as master!
I1007 19:50:03.847251 26003 sched.cpp:178] New master at master@67.195.138.9:53996
I1007 19:50:03.843282 26010 slave.cpp:547] New master detected at master@67.195.138.9:53996
I1007 19:50:03.863149 26003 status_update_manager.cpp:157] New master detected at master@67.195.138.9:53996
I1007 19:50:03.863267 26006 master.cpp:1173] Attempting to re-register slave 201310071950-160088899-53996-25983-0 at slave(134)@67.195.138.9:53996 (vesta.apache.org)
I1007 19:50:03.871075 26006 master.cpp:2170] Adding slave 201310071950-160088899-53996-25983-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I1007 19:50:03.875111 26006 master.hpp:315] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
W1007 19:50:03.879026 26006 master.cpp:2265] Possibly orphaned task 0 of framework 201310071950-160088899-53996-25983-0000 running on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:03.875118 26007 slave.cpp:648] Re-registered with master master@67.195.138.9:53996
I1007 19:50:03.883242 26009 hierarchical_allocator_process.hpp:445] Added slave 201310071950-160088899-53996-25983-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)
I1007 19:50:03.890985 26009 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310071950-160088899-53996-25983-0 in 7.922us
I1007 19:50:04.183831 26004 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201310071950-160088899-53996-25983-0000'
I1007 19:50:04.411273 26009 master.cpp:731] Re-registering framework 201310071950-160088899-53996-25983-0000 at scheduler(115)@67.195.138.9:53996
I1007 19:50:04.411478 26003 sched.cpp:237] Framework registered with 201310071950-160088899-53996-25983-0000
I1007 19:50:04.411525 26009 slave.cpp:1339] Updating framework 201310071950-160088899-53996-25983-0000 pid to scheduler(115)@67.195.138.9:53996
I1007 19:50:04.411581 26004 hierarchical_allocator_process.hpp:332] Added framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.471933 26004 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.456013 26003 sched.cpp:251] Scheduler::registered took 12.438us
I1007 19:50:04.475996 26004 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 4.094952ms
I1007 19:50:04.476058 26007 master.hpp:343] Adding offer 201310071950-160088899-53996-25983-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:04.487874 26007 master.cpp:1460] Sending 1 offers to framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.491914 26010 sched.cpp:340] Scheduler::resourceOffers took 28.346us
I1007 19:50:04.492097 25983 master.cpp:485] Master terminating
I1007 19:50:04.499779 25983 master.cpp:206] Shutting down master
I1007 19:50:04.503774 25983 master.hpp:333] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:04.499820 26008 slave.cpp:1980] master@67.195.138.9:53996 exited
W1007 19:50:04.511703 26008 slave.cpp:1983] Master disconnected! Waiting for a new master to be elected
I1007 19:50:04.507773 25983 master.hpp:353] Removing offer 201310071950-160088899-53996-25983-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:04.507877 26006 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201310071950-160088899-53996-25983-0 from framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.495826 26010 sched.cpp:498] Stopping framework '201310071950-160088899-53996-25983-0000'
I1007 19:50:04.519700 25983 master.cpp:237] Removing slave 201310071950-160088899-53996-25983-0 (vesta.apache.org)
I1007 19:50:04.532111 26003 slave.cpp:454] Slave terminating
I1007 19:50:04.546550 26010 detector.cpp:420] Master detector (slave(134)@67.195.138.9:53996) found 0 registered masters
I1007 19:50:04.555444 26010 detector.cpp:441] Master detector (slave(134)@67.195.138.9:53996) couldn't find any masters
I1007 19:50:04.551481 26003 slave.cpp:1171] Asked to shut down framework 201310071950-160088899-53996-25983-0000 by @0.0.0.0:0
I1007 19:50:04.563396 26003 slave.cpp:1196] Shutting down framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.567402 26003 slave.cpp:2433] Shutting down executor 'default' of framework 201310071950-160088899-53996-25983-0000
I1007 19:50:04.571403 26005 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2b63d4022a20)
Stack trace:
I1007 19:50:04.575384 26005 exec.cpp:386] Executor::shutdown took 23.541us
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1376 ms)
I1007 19:50:04.588665 25983 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 52020
[----------] 2 tests from AllocatorZooKeeperTest/0 (2912 ms total)
[----------] Global test environment tear-down
[==========] 224 tests from 40 test cases ran. (305441 ms total)
[ PASSED ] 223 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] CoordinatorTest.TruncateNotLearnedFill
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
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1699
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1699/changes>