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/09/22 16:56:10 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1676
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1676/>
------------------------------------------
[...truncated 17256 lines...]
I0922 14:56:07.651665 12253 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.658552 12254 slave.cpp:1773] Handling status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000 from executor(40)@67.195.138.9:60130
I0922 14:56:07.666543 12255 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.674464 12255 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.692270 12255 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000 to master@67.195.138.9:60130
I0922 14:56:07.704267 12255 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.704320 12252 master.cpp:1274] Status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000 from slave(131)@67.195.138.9:60130
I0922 14:56:07.728124 12252 master.cpp:481] Master terminating
I0922 14:56:07.716145 12255 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000 to executor(40)@67.195.138.9:60130
I0922 14:56:07.704403 12254 sched.cpp:395] Scheduler::statusUpdate took 28.939us
I0922 14:56:07.740114 12233 master.cpp:206] Shutting down master
I0922 14:56:07.776005 12233 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:07.752125 12253 exec.cpp:331] Executor received status update acknowledgement 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509 for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.752076 12255 slave.cpp:1973] master@67.195.138.9:60130 exited
I0922 14:56:07.787977 12233 master.cpp:237] Removing slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:07.788041 12259 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 201309221456-160088899-60130-12233-0 from framework 201309221456-160088899-60130-12233-0000
W0922 14:56:07.811838 12255 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected
I0922 14:56:07.836730 12258 master.cpp:265] Master started on 67.195.138.9:60130
I0922 14:56:07.876536 12258 master.cpp:280] Master ID: 201309221456-160088899-60130-12233
I0922 14:56:07.860074 12253 detector.cpp:234] Master detector (master@67.195.138.9:60130) connected to ZooKeeper ...
I0922 14:56:07.860239 12256 detector.cpp:420] Master detector (slave(131)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:07.860366 12257 detector.cpp:420] Master detector (scheduler(113)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:07.923507 12257 detector.cpp:441] Master detector (scheduler(113)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:07.887732 12254 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0922 14:56:07.887879 12259 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.9:60130
I0922 14:56:07.899637 12253 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0922 14:56:07.911520 12256 detector.cpp:441] Master detector (slave(131)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:07.983328 12256 slave.cpp:577] Lost master(s) ... waiting
I0922 14:56:07.935468 12252 sched.cpp:202] No master detected, waiting for another master
I0922 14:56:07.847808 12255 status_update_manager.cpp:375] Received status update acknowledgement (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.027884 12255 slave.cpp:1386] Status update manager successfully handled status update acknowledgement (UUID: 9b0a0c7d-e7db-4ac4-b04e-0e7cf6099509) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:07.987612 12259 detector.cpp:420] Master detector (slave(131)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:07.987782 12258 detector.cpp:420] Master detector (scheduler(113)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:08.015867 12252 sched.cpp:214] Scheduler::disconnected took 11.332us
I0922 14:56:07.987303 12253 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0922 14:56:08.052230 12259 detector.cpp:467] Master detector (slave(131)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.064120 12258 detector.cpp:467] Master detector (scheduler(113)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.080185 12253 detector.cpp:420] Master detector (master@67.195.138.9:60130) found 1 registered masters
I0922 14:56:08.087615 12259 slave.cpp:543] New master detected at master@67.195.138.9:60130
I0922 14:56:08.111515 12259 status_update_manager.cpp:157] New master detected at master@67.195.138.9:60130
I0922 14:56:08.103997 12253 detector.cpp:467] Master detector (master@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.095561 12258 sched.cpp:178] New master at master@67.195.138.9:60130
I0922 14:56:08.127496 12253 master.cpp:644] Elected as master!
I0922 14:56:08.143350 12253 master.cpp:736] Re-registering framework 201309221456-160088899-60130-12233-0000 at scheduler(113)@67.195.138.9:60130
I0922 14:56:08.151391 12254 sched.cpp:237] Framework registered with 201309221456-160088899-60130-12233-0000
I0922 14:56:08.159241 12254 sched.cpp:251] Scheduler::registered took 9.433us
I0922 14:56:08.151463 12256 hierarchical_allocator_process.hpp:321] Added framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.175148 12256 hierarchical_allocator_process.hpp:687] No resources available to allocate!
I0922 14:56:08.183106 12256 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 7.956301ms
I0922 14:56:08.364303 12256 master.cpp:1175] Attempting to re-register slave 201309221456-160088899-60130-12233-0 at slave(131)@67.195.138.9:60130 (vesta.apache.org)
I0922 14:56:08.364424 12256 master.cpp:2165] Adding slave 201309221456-160088899-60130-12233-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I0922 14:56:08.366328 12257 slave.cpp:644] Re-registered with master master@67.195.138.9:60130
I0922 14:56:08.366333 12256 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.382287 12256 slave.cpp:1332] Updating framework 201309221456-160088899-60130-12233-0000 pid to scheduler(113)@67.195.138.9:60130
I0922 14:56:08.382366 12257 hierarchical_allocator_process.hpp:434] Added slave 201309221456-160088899-60130-12233-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)
I0922 14:56:08.398195 12257 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.406224 12257 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309221456-160088899-60130-12233-0 in 8.054745ms
I0922 14:56:08.406260 12258 master.hpp:347] Adding offer 201309221456-160088899-60130-12233-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.422044 12258 master.cpp:1462] Sending 1 offers to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.430054 12252 sched.cpp:340] Scheduler::resourceOffers took 19.502us
I0922 14:56:08.430277 12233 master.cpp:481] Master terminating
I0922 14:56:08.445955 12233 master.cpp:206] Shutting down master
I0922 14:56:08.437998 12252 sched.cpp:498] Stopping framework '201309221456-160088899-60130-12233-0000'
I0922 14:56:08.445976 12255 slave.cpp:1973] master@67.195.138.9:60130 exited
I0922 14:56:08.453923 12233 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.485832 12233 master.hpp:357] Removing offer 201309221456-160088899-60130-12233-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.485921 12259 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309221456-160088899-60130-12233-0 from framework 201309221456-160088899-60130-12233-0000
W0922 14:56:08.469871 12255 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected
I0922 14:56:08.493767 12233 master.cpp:237] Removing slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.517969 12233 slave.cpp:454] Slave terminating
I0922 14:56:08.525625 12233 slave.cpp:1167] Asked to shut down framework 201309221456-160088899-60130-12233-0000 by @0.0.0.0:0
I0922 14:56:08.541584 12233 slave.cpp:1192] Shutting down framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.549548 12233 slave.cpp:2426] Shutting down executor 'default' of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.529314 12258 detector.cpp:420] Master detector (slave(131)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:08.557695 12257 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2aecac019b10)
Stack trace:
I0922 14:56:08.565512 12258 detector.cpp:441] Master detector (slave(131)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:08.573484 12257 exec.cpp:386] Executor::shutdown took 19.749us
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1457 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013
2013-09-22 14:56:08,595:12233(0x2aec9c642700):ZOO_INFO@log_env@679: Client environment:user.name=(null)
I0922 14:56:08.594127 12256 master.cpp:265] Master started on 67.195.138.9:60130
I0922 14:56:08.594918 12254 slave.cpp:108] Slave started on 132)@67.195.138.9:60130
I0922 14:56:08.621433 12254 slave.cpp:208] Slave resources: cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I0922 14:56:08.604985 12259 detector.cpp:234] Master detector (master@67.195.138.9:60130) connected to ZooKeeper ...
I0922 14:56:08.637310 12259 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0922 14:56:08.609408 12258 detector.cpp:234] Master detector (scheduler(114)@67.195.138.9:60130) connected to ZooKeeper ...
I0922 14:56:08.601418 12256 master.cpp:280] Master ID: 201309221456-160088899-60130-12233
I0922 14:56:08.629904 12252 slave.cpp:399] Finished recovery
I0922 14:56:08.608913 12255 detector.cpp:234] Master detector (slave(132)@67.195.138.9:60130) connected to ZooKeeper ...
I0922 14:56:08.653210 12258 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0922 14:56:08.656450 12259 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0922 14:56:08.669412 12257 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0922 14:56:08.669417 12254 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.9:60130
I0922 14:56:08.685132 12255 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0922 14:56:08.694236 12258 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:08.701611 12259 detector.cpp:420] Master detector (master@67.195.138.9:60130) found 1 registered masters
I0922 14:56:08.726058 12255 detector.cpp:420] Master detector (slave(132)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:08.733481 12258 detector.cpp:467] Master detector (scheduler(114)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.741528 12259 detector.cpp:467] Master detector (master@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.749444 12255 detector.cpp:467] Master detector (slave(132)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:08.772891 12255 slave.cpp:543] New master detected at master@67.195.138.9:60130
I0922 14:56:08.764904 12259 master.cpp:644] Elected as master!
I0922 14:56:08.756960 12258 sched.cpp:178] New master at master@67.195.138.9:60130
I0922 14:56:08.780876 12255 status_update_manager.cpp:157] New master detected at master@67.195.138.9:60130
I0922 14:56:08.788842 12259 master.cpp:1075] Attempting to register slave on vesta.apache.org at slave(132)@67.195.138.9:60130
I0922 14:56:08.812747 12259 master.cpp:2165] Adding slave 201309221456-160088899-60130-12233-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I0922 14:56:08.820844 12259 master.cpp:694] Registering framework 201309221456-160088899-60130-12233-0000 at scheduler(114)@67.195.138.9:60130
I0922 14:56:08.820863 12254 slave.cpp:603] Registered with master master@67.195.138.9:60130; given slave ID 201309221456-160088899-60130-12233-0
I0922 14:56:08.820930 12252 hierarchical_allocator_process.hpp:434] Added slave 201309221456-160088899-60130-12233-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)
I0922 14:56:08.828738 12259 sched.cpp:237] Framework registered with 201309221456-160088899-60130-12233-0000
I0922 14:56:08.844655 12252 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309221456-160088899-60130-12233-0 in 5.449us
I0922 14:56:08.852638 12259 sched.cpp:251] Scheduler::registered took 11.837us
I0922 14:56:08.860613 12252 hierarchical_allocator_process.hpp:321] Added framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.876571 12252 hierarchical_allocator_process.hpp:712] Offering cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.884614 12252 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 8.065859ms
I0922 14:56:08.884645 12254 master.hpp:347] Adding offer 201309221456-160088899-60130-12233-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.900501 12254 master.cpp:1462] Sending 1 offers to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.908678 12256 sched.cpp:340] Scheduler::resourceOffers took 148.064us
I0922 14:56:08.916519 12254 master.cpp:1699] Processing reply for offer 201309221456-160088899-60130-12233-0 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org) for framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.924470 12254 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.932387 12254 master.cpp:1823] Launching task 0 of framework 201309221456-160088899-60130-12233-0000 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.940459 12254 master.hpp:357] Removing offer 201309221456-160088899-60130-12233-0 with resources cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:08.940495 12258 slave.cpp:772] Got assigned task 0 for framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.956454 12258 slave.cpp:883] Launching task 0 for framework 201309221456-160088899-60130-12233-0000
I0922 14:56:08.940516 12257 hierarchical_allocator_process.hpp:508] Framework 201309221456-160088899-60130-12233-0000 left cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave 201309221456-160088899-60130-12233-0
I0922 14:56:08.972323 12257 hierarchical_allocator_process.hpp:551] Framework 201309221456-160088899-60130-12233-0000 filtered slave 201309221456-160088899-60130-12233-0 for 5secs
I0922 14:56:08.967033 12252 exec.cpp:174] Executor started at: executor(41)@67.195.138.9:60130 with pid 12233
I0922 14:56:08.965772 12258 slave.cpp:994] Queuing task '0' for executor default of framework '201309221456-160088899-60130-12233-0000
I0922 14:56:08.996217 12258 slave.cpp:525] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_AVPAct/slaves/201309221456-160088899-60130-12233-0/frameworks/201309221456-160088899-60130-12233-0000/executors/default/runs/8590847b-4b83-4938-8e2d-128a466d8286'
I0922 14:56:09.004222 12258 slave.cpp:1440] Got registration for executor 'default' of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.012228 12258 slave.cpp:1561] Flushing queued task 0 for executor 'default' of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.012233 12252 exec.cpp:198] Executor registered on slave 201309221456-160088899-60130-12233-0
I0922 14:56:09.029222 12252 exec.cpp:210] Executor::registered took 11.416us
I0922 14:56:09.036073 12252 exec.cpp:285] Executor asked to run task '0'
I0922 14:56:09.044056 12252 exec.cpp:294] Executor::launchTask took 19.632us
I0922 14:56:09.053133 12252 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.060036 12252 slave.cpp:1773] Handling status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000 from executor(41)@67.195.138.9:60130
I0922 14:56:09.068042 12252 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.075958 12252 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.083955 12252 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000 to master@67.195.138.9:60130
I0922 14:56:09.092010 12259 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.092041 12255 master.cpp:1274] Status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000 from slave(132)@67.195.138.9:60130
I0922 14:56:09.092118 12254 sched.cpp:395] Scheduler::statusUpdate took 24.542us
I0922 14:56:09.099885 12259 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000 to executor(41)@67.195.138.9:60130
I0922 14:56:09.107888 12255 master.cpp:481] Master terminating
I0922 14:56:09.123953 12256 exec.cpp:331] Executor received status update acknowledgement 59efb089-19c1-4e66-9125-42e7336e943c for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.123955 12258 status_update_manager.cpp:375] Received status update acknowledgement (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.131852 12257 slave.cpp:1973] master@67.195.138.9:60130 exited
I0922 14:56:09.131855 12233 master.cpp:206] Shutting down master
I0922 14:56:09.163725 12233 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:09.171718 12233 master.cpp:237] Removing slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:09.171766 12258 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 201309221456-160088899-60130-12233-0 from framework 201309221456-160088899-60130-12233-0000
W0922 14:56:09.155709 12257 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected
I0922 14:56:09.188384 12259 master.cpp:265] Master started on 67.195.138.9:60130
I0922 14:56:09.199545 12257 slave.cpp:1386] Status update manager successfully handled status update acknowledgement (UUID: 59efb089-19c1-4e66-9125-42e7336e943c) for task 0 of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:09.210562 12259 master.cpp:280] Master ID: 201309221456-160088899-60130-12233
I0922 14:56:09.214527 12252 detector.cpp:234] Master detector (master@67.195.138.9:60130) connected to ZooKeeper ...
I0922 14:56:09.214753 12256 detector.cpp:420] Master detector (slave(132)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:09.214890 12254 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:09.235621 12253 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0922 14:56:09.235782 12257 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.9:60130
I0922 14:56:09.243445 12252 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0922 14:56:09.251427 12256 detector.cpp:441] Master detector (slave(132)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:09.259402 12254 detector.cpp:441] Master detector (scheduler(114)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:09.293999 12252 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0922 14:56:09.299348 12259 slave.cpp:577] Lost master(s) ... waiting
I0922 14:56:09.299895 12256 detector.cpp:420] Master detector (slave(132)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:09.307319 12255 sched.cpp:202] No master detected, waiting for another master
I0922 14:56:09.307814 12254 detector.cpp:420] Master detector (scheduler(114)@67.195.138.9:60130) found 1 registered masters
I0922 14:56:09.315776 12252 detector.cpp:420] Master detector (master@67.195.138.9:60130) found 1 registered masters
I0922 14:56:09.331718 12256 detector.cpp:467] Master detector (slave(132)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:09.339136 12255 sched.cpp:214] Scheduler::disconnected took 10.236us
I0922 14:56:09.347609 12254 detector.cpp:467] Master detector (scheduler(114)@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:09.379057 12254 sched.cpp:178] New master at master@67.195.138.9:60130
I0922 14:56:09.363154 12256 slave.cpp:543] New master detected at master@67.195.138.9:60130
I0922 14:56:09.355582 12252 detector.cpp:467] Master detector (master@67.195.138.9:60130) got new master pid: master@67.195.138.9:60130
I0922 14:56:09.395027 12259 status_update_manager.cpp:157] New master detected at master@67.195.138.9:60130
W0922 14:56:09.395144 12258 master.cpp:1102] Ignoring re-register slave message from vesta.apache.org since not elected yet
I0922 14:56:09.418901 12258 master.cpp:644] Elected as master!
I0922 14:56:09.781628 12257 master.cpp:1175] Attempting to re-register slave 201309221456-160088899-60130-12233-0 at slave(132)@67.195.138.9:60130 (vesta.apache.org)
I0922 14:56:09.781743 12257 master.cpp:2165] Adding slave 201309221456-160088899-60130-12233-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1.38501e+06; ports(*):[31000-32000]
I0922 14:56:09.821658 12254 slave.cpp:644] Re-registered with master master@67.195.138.9:60130
I0922 14:56:09.821678 12257 master.hpp:321] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
W0922 14:56:09.837574 12257 master.cpp:2260] Possibly orphaned task 0 of framework 201309221456-160088899-60130-12233-0000 running on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:09.845693 12254 hierarchical_allocator_process.hpp:434] Added slave 201309221456-160088899-60130-12233-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)
I0922 14:56:09.853523 12254 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309221456-160088899-60130-12233-0 in 7.325us
I0922 14:56:10.004992 12257 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201309221456-160088899-60130-12233-0000'
I0922 14:56:10.276211 12257 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 8.02us
I0922 14:56:10.387384 12259 master.cpp:736] Re-registering framework 201309221456-160088899-60130-12233-0000 at scheduler(114)@67.195.138.9:60130
I0922 14:56:10.387614 12252 sched.cpp:237] Framework registered with 201309221456-160088899-60130-12233-0000
I0922 14:56:10.405133 12252 sched.cpp:251] Scheduler::registered took 9.148us
I0922 14:56:10.387699 12256 hierarchical_allocator_process.hpp:321] Added framework 201309221456-160088899-60130-12233-0000
I0922 14:56:10.387651 12259 slave.cpp:1332] Updating framework 201309221456-160088899-60130-12233-0000 pid to scheduler(114)@67.195.138.9:60130
I0922 14:56:10.425066 12256 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:10.445099 12256 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 20.056706ms
I0922 14:56:10.445138 12254 master.hpp:347] Adding offer 201309221456-160088899-60130-12233-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:10.464860 12254 master.cpp:1462] Sending 1 offers to framework 201309221456-160088899-60130-12233-0000
I0922 14:56:10.475657 12254 sched.cpp:340] Scheduler::resourceOffers took 19.521us
I0922 14:56:10.475852 12233 master.cpp:481] Master terminating
I0922 14:56:10.495548 12233 master.cpp:206] Shutting down master
I0922 14:56:10.504694 12233 master.hpp:337] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:10.495564 12252 slave.cpp:1973] master@67.195.138.9:60130 exited
I0922 14:56:10.484768 12254 sched.cpp:498] Stopping framework '201309221456-160088899-60130-12233-0000'
I0922 14:56:10.515507 12233 master.hpp:357] Removing offer 201309221456-160088899-60130-12233-0 with resources cpus(*):1; mem(*):524; disk(*):1.38501e+06; ports(*):[31000-32000] on slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:10.515589 12259 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309221456-160088899-60130-12233-0 from framework 201309221456-160088899-60130-12233-0000
W0922 14:56:10.524566 12252 slave.cpp:1976] Master disconnected! Waiting for a new master to be elected
I0922 14:56:10.551367 12233 master.cpp:237] Removing slave 201309221456-160088899-60130-12233-0 (vesta.apache.org)
I0922 14:56:10.576611 12233 slave.cpp:454] Slave terminating
I0922 14:56:10.589151 12252 detector.cpp:420] Master detector (slave(132)@67.195.138.9:60130) found 0 registered masters
I0922 14:56:10.600215 12252 detector.cpp:441] Master detector (slave(132)@67.195.138.9:60130) couldn't find any masters
I0922 14:56:10.591217 12233 slave.cpp:1167] Asked to shut down framework 201309221456-160088899-60130-12233-0000 by @0.0.0.0:0
I0922 14:56:10.616143 12233 slave.cpp:1192] Shutting down framework 201309221456-160088899-60130-12233-0000
I0922 14:56:10.624097 12233 slave.cpp:2426] Shutting down executor 'default' of framework 201309221456-160088899-60130-12233-0000
I0922 14:56:10.632092 12258 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2aecb00243a0)
Stack trace:
I0922 14:56:10.644050 12258 exec.cpp:386] Executor::shutdown took 20.164us
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2059 ms)
I0922 14:56:10.653432 12233 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 54133
[----------] 2 tests from AllocatorZooKeeperTest/0 (3516 ms total)
[----------] Global test environment tear-down
[==========] 220 tests from 39 test cases ran. (196727 ms total)
[ PASSED ] 219 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 `/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 #1677
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/1677/>