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/07/12 05:52:41 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1352
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1352/>
------------------------------------------
[...truncated 14833 lines...]
I0712 03:52:37.597429 4392 slave.cpp:511] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_FrameworkReregistersFirst_if88jr/slaves/201307120352-160088899-47248-4046-0/frameworks/201307120352-160088899-47248-4046-0000/executors/default/runs/f96cb0ef-3f8d-4de7-bda6-ee13be68244f'
I0712 03:52:37.613379 4392 slave.cpp:1395] Got registration for executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.621342 4392 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.621428 4393 exec.cpp:194] Executor registered on slave 201307120352-160088899-47248-4046-0
I0712 03:52:37.637296 4393 exec.cpp:258] Executor asked to run task '0'
I0712 03:52:37.646359 4393 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.653203 4393 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000 from executor(37)@67.195.138.9:47248
I0712 03:52:37.661207 4396 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000 with checkpoint=false
I0712 03:52:37.669098 4396 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.677126 4396 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000 to master@67.195.138.9:47248
I0712 03:52:37.685096 4396 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.685106 4393 master.cpp:1093] Status update from slave(100)@67.195.138.9:47248: task 0 of framework 201307120352-160088899-47248-4046-0000 is now in state TASK_RUNNING
I0712 03:52:37.693004 4396 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 7a67057c-495f-458d-bc52-beec09e78f10) for task 0 of framework 201307120352-160088899-47248-4046-0000 to executor(37)@67.195.138.9:47248
I0712 03:52:37.701539 4046 master.cpp:436] Master terminating
I0712 03:52:37.716959 4046 master.cpp:209] Shutting down master
I0712 03:52:37.709167 4398 status_update_manager.cpp:360] Received status update acknowledgement 7a67057c-495f-458d-bc52-beec09e78f10 for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.709074 4392 exec.cpp:290] Executor received status update acknowledgement 7a67057c-495f-458d-bc52-beec09e78f10 for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.717015 4393 slave.cpp:1884] master@67.195.138.9:47248 exited
I0712 03:52:37.724942 4394 hierarchical_allocator_process.hpp:411] Deactivated framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.724941 4046 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
W0712 03:52:37.748776 4393 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0712 03:52:37.764915 4396 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=783447) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.772734 4393 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by master@67.195.138.9:47248
I0712 03:52:37.781620 4395 master.cpp:230] Master started on 67.195.138.9:47248
I0712 03:52:37.812600 4395 master.cpp:245] Master ID: 201307120352-160088899-47248-4046
I0712 03:52:37.805613 4397 detector.cpp:234] Master detector (master@67.195.138.9:47248) connected to ZooKeeper ...
I0712 03:52:37.805819 4394 detector.cpp:420] Master detector (slave(100)@67.195.138.9:47248) found 0 registered masters
I0712 03:52:37.836457 4394 detector.cpp:441] Master detector (slave(100)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:37.788692 4393 slave.cpp:1137] Shutting down framework 201307120352-160088899-47248-4046-0000
W0712 03:52:37.820760 4392 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0712 03:52:37.820972 4399 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:47248
I0712 03:52:37.828495 4397 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0712 03:52:37.805933 4396 detector.cpp:420] Master detector (scheduler(93)@67.195.138.9:47248) found 0 registered masters
I0712 03:52:37.884273 4396 detector.cpp:441] Master detector (scheduler(93)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:37.900281 4394 sched.cpp:194] No master detected, waiting for another master
I0712 03:52:37.889061 4397 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0712 03:52:37.889449 4398 detector.cpp:420] Master detector (slave(100)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:37.852399 4393 slave.cpp:2328] Shutting down executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.900728 4396 detector.cpp:420] Master detector (scheduler(93)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:37.916700 4397 detector.cpp:420] Master detector (master@67.195.138.9:47248) found 1 registered masters
I0712 03:52:37.924662 4398 detector.cpp:467] Master detector (slave(100)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:37.932117 4393 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 7a67057c-495f-458d-bc52-beec09e78f10 for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:37.963979 4393 slave.cpp:563] Lost master(s) ... waiting
I0712 03:52:37.940561 4396 detector.cpp:467] Master detector (scheduler(93)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:37.979936 4396 sched.cpp:177] New master at master@67.195.138.9:47248
W0712 03:52:37.987901 4396 master.cpp:655] Ignoring re-register framework message since not elected yet
I0712 03:52:37.971957 4393 slave.cpp:529] New master detected at master@67.195.138.9:47248
I0712 03:52:37.948573 4397 detector.cpp:467] Master detector (master@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:37.932139 4394 exec.cpp:323] Executor asked to shutdown
I0712 03:52:38.003845 4395 status_update_manager.cpp:155] New master detected at master@67.195.138.9:47248
I0712 03:52:38.011829 4397 master.cpp:583] Elected as master!
I0712 03:52:38.868991 4393 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0712 03:52:38.869086 4393 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 105.622us
I0712 03:52:38.988289 4392 master.cpp:675] Re-registering framework 201307120352-160088899-47248-4046-0000 at scheduler(93)@67.195.138.9:47248
I0712 03:52:38.988581 4398 sched.cpp:246] Framework re-registered with 201307120352-160088899-47248-4046-0000
I0712 03:52:38.988661 4396 hierarchical_allocator_process.hpp:335] Added framework 201307120352-160088899-47248-4046-0000
I0712 03:52:38.999805 4396 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0712 03:52:39.004438 4392 master.cpp:1034] Attempting to re-register slave 201307120352-160088899-47248-4046-0 at slave(100)@67.195.138.9:47248 (vesta.apache.org)
I0712 03:52:39.015743 4392 master.cpp:1929] Adding slave 201307120352-160088899-47248-4046-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=783447
I0712 03:52:39.023805 4392 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.023844 4397 slave.cpp:630] Re-registered with master master@67.195.138.9:47248
W0712 03:52:39.039667 4397 slave.cpp:1273] Ignoring updating pid for framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:39.007758 4396 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 7.956627ms
I0712 03:52:39.057432 4396 hierarchical_allocator_process.hpp:448] Added slave 201307120352-160088899-47248-4046-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=783447 (and cpus=1; mem=524; ports=[31000-32000]; disk=783447 available)
I0712 03:52:39.065474 4396 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=783447 on slave 201307120352-160088899-47248-4046-0 to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.073369 4396 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307120352-160088899-47248-4046-0 in 7.92539ms
I0712 03:52:39.073467 4392 master.hpp:327] Adding offer 201307120352-160088899-47248-4046-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=783447 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.089207 4392 master.cpp:1310] Sending 1 offers to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.097470 4392 sched.cpp:427] Stopping framework '201307120352-160088899-47248-4046-0000'
I0712 03:52:39.097496 4046 master.cpp:436] Master terminating
I0712 03:52:39.113077 4046 master.cpp:209] Shutting down master
I0712 03:52:39.159315 4046 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.167208 4046 master.hpp:337] Removing offer 201307120352-160088899-47248-4046-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=783447 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.113106 4399 slave.cpp:485] Slave asked to shut down by master@67.195.138.9:47248
I0712 03:52:39.183061 4399 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by master@67.195.138.9:47248
W0712 03:52:39.200590 4399 slave.cpp:1133] Ignoring shutdown framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:39.188237 4398 detector.cpp:420] Master detector (slave(100)@67.195.138.9:47248) found 0 registered masters
I0712 03:52:39.159402 4395 hierarchical_allocator_process.hpp:411] Deactivated framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.226994 4395 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.216558 4398 detector.cpp:441] Master detector (slave(100)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:39.208557 4399 slave.cpp:440] Slave terminating
I0712 03:52:39.250803 4399 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by @0.0.0.0:0
W0712 03:52:39.258749 4399 slave.cpp:1133] Ignoring shutdown framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:39.234925 4395 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=783447 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=783447) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (3165 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
2013-07-12 03:52:39,290:4046(0x2b7d0cf75700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:36322]
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@662: Client environment:host.name=vesta.apache.org
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-38-generic
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@671: Client environment:os.version=#61-Ubuntu SMP Tue Feb 19 12:18:21 UTC 2013
2013-07-12 03:52:39,290:4046(0x2b7bf75a2700):ZOO_INFO@log_env@679: Client environment:user.name=(null)
I0712 03:52:39.289599 4397 master.cpp:230] Master started on 67.195.138.9:47248
I0712 03:52:39.290143 4396 slave.cpp:113] Slave started on 101)@67.195.138.9:47248
I0712 03:52:39.328987 4396 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=783450
I0712 03:52:39.304085 4394 detector.cpp:234] Master detector (scheduler(94)@67.195.138.9:47248) connected to ZooKeeper ...
I0712 03:52:39.304445 4398 detector.cpp:234] Master detector (slave(101)@67.195.138.9:47248) connected to ZooKeeper ...
I0712 03:52:39.316997 4397 master.cpp:245] Master ID: 201307120352-160088899-47248-4046
I0712 03:52:39.303617 4395 detector.cpp:234] Master detector (master@67.195.138.9:47248) connected to ZooKeeper ...
I0712 03:52:39.388689 4395 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0712 03:52:39.352818 4394 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0712 03:52:39.364758 4398 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
W0712 03:52:39.376893 4393 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0712 03:52:39.376935 4399 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:47248
I0712 03:52:39.341560 4396 slave.cpp:390] Finished recovery
I0712 03:52:39.410809 4395 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0712 03:52:39.413496 4394 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:39.425420 4398 detector.cpp:420] Master detector (slave(101)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:39.469138 4395 detector.cpp:420] Master detector (master@67.195.138.9:47248) found 1 registered masters
I0712 03:52:39.480869 4394 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:39.492835 4398 detector.cpp:467] Master detector (slave(101)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:39.504783 4395 detector.cpp:467] Master detector (master@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:39.516369 4392 sched.cpp:177] New master at master@67.195.138.9:47248
I0712 03:52:39.528342 4398 slave.cpp:529] New master detected at master@67.195.138.9:47248
I0712 03:52:39.564184 4398 status_update_manager.cpp:155] New master detected at master@67.195.138.9:47248
I0712 03:52:39.540274 4395 master.cpp:583] Elected as master!
I0712 03:52:39.588384 4395 master.cpp:633] Registering framework 201307120352-160088899-47248-4046-0000 at scheduler(94)@67.195.138.9:47248
I0712 03:52:39.588512 4395 master.cpp:962] Attempting to register slave on vesta.apache.org at slave(101)@67.195.138.9:47248
I0712 03:52:39.588532 4394 sched.cpp:222] Framework registered with 201307120352-160088899-47248-4046-0000
I0712 03:52:39.588534 4395 master.cpp:1929] Adding slave 201307120352-160088899-47248-4046-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=783450
I0712 03:52:39.588729 4396 slave.cpp:589] Registered with master master@67.195.138.9:47248; given slave ID 201307120352-160088899-47248-4046-0
I0712 03:52:39.588608 4397 hierarchical_allocator_process.hpp:335] Added framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.588786 4397 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0712 03:52:39.588800 4397 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 15.752us
I0712 03:52:39.588866 4397 hierarchical_allocator_process.hpp:448] Added slave 201307120352-160088899-47248-4046-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=783450 (and cpus=2; mem=1024; ports=[31000-32000]; disk=783450 available)
I0712 03:52:39.588953 4397 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0 to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.589062 4397 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307120352-160088899-47248-4046-0 in 119.813us
I0712 03:52:39.589138 4396 master.hpp:327] Adding offer 201307120352-160088899-47248-4046-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.589211 4396 master.cpp:1310] Sending 1 offers to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.589481 4396 master.cpp:1543] Processing reply for offer 201307120352-160088899-47248-4046-0 on slave 201307120352-160088899-47248-4046-0 (vesta.apache.org) for framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.589570 4396 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.589601 4396 master.cpp:1662] Launching task 0 of framework 201307120352-160088899-47248-4046-0000 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0 (vesta.apache.org)
I0712 03:52:39.589684 4399 slave.cpp:739] Got assigned task 0 for framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.589715 4396 master.hpp:337] Removing offer 201307120352-160088899-47248-4046-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.589781 4396 hierarchical_allocator_process.hpp:525] Framework 201307120352-160088899-47248-4046-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=783450 unused on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.589872 4396 hierarchical_allocator_process.hpp:568] Framework 201307120352-160088899-47248-4046-0000 filtered slave 201307120352-160088899-47248-4046-0 for 5secs
I0712 03:52:39.589896 4399 slave.cpp:837] Launching task 0 for framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.591325 4399 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_XIjloL/slaves/201307120352-160088899-47248-4046-0/frameworks/201307120352-160088899-47248-4046-0000/executors/default/runs/390e5c1b-1eaf-49a9-a2d8-33ddd031d3a4'
I0712 03:52:39.591480 4399 slave.cpp:948] Queuing task '0' for executor default of framework '201307120352-160088899-47248-4046-0000
I0712 03:52:39.591624 4394 exec.cpp:170] Executor started at: executor(38)@67.195.138.9:47248 with pid 4046
I0712 03:52:39.591651 4399 slave.cpp:511] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_XIjloL/slaves/201307120352-160088899-47248-4046-0/frameworks/201307120352-160088899-47248-4046-0000/executors/default/runs/390e5c1b-1eaf-49a9-a2d8-33ddd031d3a4'
I0712 03:52:39.591733 4399 slave.cpp:1395] Got registration for executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.591811 4399 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.591955 4399 exec.cpp:194] Executor registered on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.592012 4399 exec.cpp:258] Executor asked to run task '0'
I0712 03:52:39.593305 4399 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.593397 4393 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000 from executor(38)@67.195.138.9:47248
I0712 03:52:39.593641 4392 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000 with checkpoint=false
I0712 03:52:39.593672 4392 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.593772 4392 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000 to master@67.195.138.9:47248
I0712 03:52:39.593869 4399 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.593883 4393 master.cpp:1093] Status update from slave(101)@67.195.138.9:47248: task 0 of framework 201307120352-160088899-47248-4046-0000 is now in state TASK_RUNNING
I0712 03:52:39.593904 4399 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b) for task 0 of framework 201307120352-160088899-47248-4046-0000 to executor(38)@67.195.138.9:47248
I0712 03:52:39.594033 4393 exec.cpp:290] Executor received status update acknowledgement 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594138 4393 status_update_manager.cpp:360] Received status update acknowledgement 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594395 4394 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 23b25dfb-4fd1-4ce3-aa24-7e4bd3e1047b for task 0 of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594406 4399 master.cpp:436] Master terminating
I0712 03:52:39.594550 4046 master.cpp:209] Shutting down master
I0712 03:52:39.594574 4397 slave.cpp:1884] master@67.195.138.9:47248 exited
W0712 03:52:39.594604 4397 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0712 03:52:39.594624 4398 hierarchical_allocator_process.hpp:411] Deactivated framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594635 4046 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.594651 4397 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by master@67.195.138.9:47248
I0712 03:52:39.594698 4397 slave.cpp:1137] Shutting down framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594723 4397 slave.cpp:2328] Shutting down executor 'default' of framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594796 4397 exec.cpp:323] Executor asked to shutdown
I0712 03:52:39.594787 4398 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=783450) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.594986 4398 hierarchical_allocator_process.hpp:366] Removed framework 201307120352-160088899-47248-4046-0000
I0712 03:52:39.595062 4398 hierarchical_allocator_process.hpp:476] Removed slave 201307120352-160088899-47248-4046-0
I0712 03:52:39.595760 4394 master.cpp:230] Master started on 67.195.138.9:47248
I0712 03:52:39.595813 4394 master.cpp:245] Master ID: 201307120352-160088899-47248-4046
W0712 03:52:39.596094 4399 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0712 03:52:39.596334 4394 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.9:47248
I0712 03:52:39.833879 4398 detector.cpp:234] Master detector (master@67.195.138.9:47248) connected to ZooKeeper ...
I0712 03:52:39.834014 4398 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0712 03:52:39.834246 4399 detector.cpp:420] Master detector (slave(101)@67.195.138.9:47248) found 0 registered masters
I0712 03:52:40.163229 4399 detector.cpp:441] Master detector (slave(101)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:39.834247 4392 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:47248) found 0 registered masters
I0712 03:52:40.166132 4398 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0712 03:52:40.175359 4393 slave.cpp:563] Lost master(s) ... waiting
I0712 03:52:40.176080 4399 detector.cpp:420] Master detector (slave(101)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:40.183176 4392 detector.cpp:441] Master detector (scheduler(94)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:40.192209 4398 detector.cpp:420] Master detector (master@67.195.138.9:47248) found 1 registered masters
I0712 03:52:40.207995 4399 detector.cpp:467] Master detector (slave(101)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:40.215061 4396 sched.cpp:194] No master detected, waiting for another master
I0712 03:52:40.215842 4392 detector.cpp:420] Master detector (scheduler(94)@67.195.138.9:47248) found 1 registered masters
I0712 03:52:40.223716 4398 detector.cpp:467] Master detector (master@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:40.231047 4399 slave.cpp:529] New master detected at master@67.195.138.9:47248
I0712 03:52:40.247593 4392 detector.cpp:467] Master detector (scheduler(94)@67.195.138.9:47248) got new master pid: master@67.195.138.9:47248
I0712 03:52:40.254866 4398 master.cpp:583] Elected as master!
I0712 03:52:40.262840 4397 status_update_manager.cpp:155] New master detected at master@67.195.138.9:47248
I0712 03:52:40.270755 4392 sched.cpp:177] New master at master@67.195.138.9:47248
I0712 03:52:40.278724 4398 master.cpp:1034] Attempting to re-register slave 201307120352-160088899-47248-4046-0 at slave(101)@67.195.138.9:47248 (vesta.apache.org)
I0712 03:52:40.302512 4398 master.cpp:1929] Adding slave 201307120352-160088899-47248-4046-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=783450
I0712 03:52:40.310540 4394 slave.cpp:630] Re-registered with master master@67.195.138.9:47248
I0712 03:52:40.310544 4398 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
W0712 03:52:40.326397 4398 master.cpp:2021] Possibly orphaned task 0 of framework 201307120352-160088899-47248-4046-0000 running on slave 201307120352-160088899-47248-4046-0 (vesta.apache.org)
I0712 03:52:40.334595 4399 hierarchical_allocator_process.hpp:448] Added slave 201307120352-160088899-47248-4046-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=783450 (and cpus=1; mem=524; ports=[31000-32000]; disk=783450 available)
I0712 03:52:40.342350 4399 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307120352-160088899-47248-4046-0 in 22.404us
I0712 03:52:40.552625 4392 master.cpp:675] Re-registering framework 201307120352-160088899-47248-4046-0000 at scheduler(94)@67.195.138.9:47248
I0712 03:52:40.552923 4395 sched.cpp:246] Framework re-registered with 201307120352-160088899-47248-4046-0000
W0712 03:52:40.552940 4392 slave.cpp:1273] Ignoring updating pid for framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:40.553046 4397 hierarchical_allocator_process.hpp:335] Added framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.577088 4397 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0 to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.585125 4397 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 8.06633ms
I0712 03:52:40.585208 4399 master.hpp:327] Adding offer 201307120352-160088899-47248-4046-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0
I0712 03:52:40.596866 4394 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 21.49us
I0712 03:52:40.600992 4399 master.cpp:1310] Sending 1 offers to framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.617359 4398 sched.cpp:427] Stopping framework '201307120352-160088899-47248-4046-0000'
I0712 03:52:40.617367 4395 master.cpp:436] Master terminating
I0712 03:52:40.632838 4395 slave.cpp:485] Slave asked to shut down by master@67.195.138.9:47248
I0712 03:52:40.632905 4046 master.cpp:209] Shutting down master
I0712 03:52:40.640758 4395 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by master@67.195.138.9:47248
I0712 03:52:40.648783 4397 hierarchical_allocator_process.hpp:411] Deactivated framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.648787 4046 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307120352-160088899-47248-4046-0
W0712 03:52:40.656671 4395 slave.cpp:1133] Ignoring shutdown framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:40.680541 4395 slave.cpp:1884] master@67.195.138.9:47248 exited
I0712 03:52:40.672724 4394 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.672674 4046 master.hpp:337] Removing offer 201307120352-160088899-47248-4046-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=783450 on slave 201307120352-160088899-47248-4046-0
W0712 03:52:40.688493 4395 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0712 03:52:40.712363 4395 slave.cpp:440] Slave terminating
I0712 03:52:40.720322 4395 slave.cpp:1112] Asked to shut down framework 201307120352-160088899-47248-4046-0000 by @0.0.0.0:0
I0712 03:52:40.696532 4394 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=783450 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=783450) on slave 201307120352-160088899-47248-4046-0 from framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.710523 4397 detector.cpp:420] Master detector (slave(101)@67.195.138.9:47248) found 0 registered masters
W0712 03:52:40.728279 4395 slave.cpp:1133] Ignoring shutdown framework 201307120352-160088899-47248-4046-0000 because it is terminating
I0712 03:52:40.736302 4394 hierarchical_allocator_process.hpp:366] Removed framework 201307120352-160088899-47248-4046-0000
I0712 03:52:40.744204 4397 detector.cpp:441] Master detector (slave(101)@67.195.138.9:47248) couldn't find any masters
I0712 03:52:40.760190 4394 hierarchical_allocator_process.hpp:476] Removed slave 201307120352-160088899-47248-4046-0
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1487 ms)
I0712 03:52:40.777847 4046 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 36322
[----------] 2 tests from AllocatorZooKeeperTest/0 (4652 ms total)
[----------] Global test environment tear-down
[==========] 198 tests from 37 test cases ran. (243711 ms total)
[ PASSED ] 196 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] MasterTest.MasterLost
[ FAILED ] AllocatorTest/0.SchedulerFailover, where TypeParam = mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter>
2 FAILED TESTS
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/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 #1353
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/1353/>