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/17 16:27:53 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1188
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1188/>
------------------------------------------
[...truncated 14200 lines...]
I0717 14:27:39.446574 8662 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: f4793abd-464e-416d-98dc-46acfc7beb8f) for task 0 of framework 201307171427-982172483-41892-8635-0000 to master@67.195.138.58:41892
I0717 14:27:39.447141 8667 master.cpp:1093] Status update from slave(100)@67.195.138.58:41892: task 0 of framework 201307171427-982172483-41892-8635-0000 is now in state TASK_RUNNING
I0717 14:27:39.447162 8661 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: f4793abd-464e-416d-98dc-46acfc7beb8f) for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.447662 8664 sched.cpp:395] Scheduler::statusUpdate took 26.261us
I0717 14:27:39.448003 8635 master.cpp:436] Master terminating
I0717 14:27:39.448084 8661 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: f4793abd-464e-416d-98dc-46acfc7beb8f) for task 0 of framework 201307171427-982172483-41892-8635-0000 to executor(37)@67.195.138.58:41892
I0717 14:27:39.449161 8635 master.cpp:209] Shutting down master
I0717 14:27:39.450336 8666 exec.cpp:320] Executor received status update acknowledgement f4793abd-464e-416d-98dc-46acfc7beb8f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.450506 8661 slave.cpp:1884] master@67.195.138.58:41892 exited
I0717 14:27:39.450556 8665 status_update_manager.cpp:360] Received status update acknowledgement f4793abd-464e-416d-98dc-46acfc7beb8f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.450845 8667 hierarchical_allocator_process.hpp:411] Deactivated framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.450852 8635 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
W0717 14:27:39.451764 8661 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0717 14:27:39.453361 8663 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21163) on slave 201307171427-982172483-41892-8635-0 from framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.454270 8661 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by master@67.195.138.58:41892
I0717 14:27:39.455576 8661 slave.cpp:1137] Shutting down framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.455782 8664 master.cpp:230] Master started on 67.195.138.58:41892
I0717 14:27:39.457002 8664 master.cpp:245] Master ID: 201307171427-982172483-41892-8635
I0717 14:27:39.456055 8661 slave.cpp:2328] Shutting down executor 'default' of framework 201307171427-982172483-41892-8635-0000
W0717 14:27:39.487972 8662 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0717 14:27:39.488121 8664 exec.cpp:360] Executor asked to shutdown
I0717 14:27:39.488124 8661 slave.cpp:1343] Status update manager successfully handled status update acknowledgement f4793abd-464e-416d-98dc-46acfc7beb8f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:39.488231 8666 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.58:41892
I0717 14:27:39.489891 8664 exec.cpp:375] Executor::shutdown took 12.188us
I0717 14:27:39.516872 8667 detector.cpp:234] Master detector (master@67.195.138.58:41892) connected to ZooKeeper ...
I0717 14:27:39.517494 8665 detector.cpp:420] Master detector (slave(100)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:39.517565 8660 detector.cpp:420] Master detector (scheduler(93)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:39.518861 8660 detector.cpp:441] Master detector (scheduler(93)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:39.518359 8665 detector.cpp:441] Master detector (slave(100)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:39.517886 8667 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0717 14:27:39.519361 8660 sched.cpp:202] No master detected, waiting for another master
I0717 14:27:39.519845 8665 slave.cpp:563] Lost master(s) ... waiting
I0717 14:27:39.520798 8660 sched.cpp:214] Scheduler::disconnected took 12.83us
I0717 14:27:39.546102 8667 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0717 14:27:39.546565 8666 detector.cpp:420] Master detector (slave(100)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:39.546804 8663 detector.cpp:420] Master detector (scheduler(93)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:39.547103 8667 detector.cpp:420] Master detector (master@67.195.138.58:41892) found 1 registered masters
I0717 14:27:39.547369 8666 detector.cpp:467] Master detector (slave(100)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:39.551450 8663 detector.cpp:467] Master detector (scheduler(93)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:39.551551 8660 slave.cpp:529] New master detected at master@67.195.138.58:41892
I0717 14:27:39.551899 8661 status_update_manager.cpp:155] New master detected at master@67.195.138.58:41892
I0717 14:27:39.551556 8663 sched.cpp:178] New master at master@67.195.138.58:41892
I0717 14:27:39.552093 8667 detector.cpp:467] Master detector (master@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
W0717 14:27:39.552856 8665 master.cpp:655] Ignoring re-register framework message since not elected yet
I0717 14:27:39.553885 8665 master.cpp:583] Elected as master!
I0717 14:27:40.424267 8663 master.cpp:675] Re-registering framework 201307171427-982172483-41892-8635-0000 at scheduler(93)@67.195.138.58:41892
I0717 14:27:40.431808 8664 sched.cpp:268] Framework re-registered with 201307171427-982172483-41892-8635-0000
I0717 14:27:40.431902 8662 hierarchical_allocator_process.hpp:335] Added framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.494976 8662 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0717 14:27:40.495483 8662 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 504.897us
I0717 14:27:40.494988 8664 sched.cpp:282] Scheduler::reregistered took 53.727us
I0717 14:27:40.517179 8663 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0717 14:27:40.517287 8663 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 137.772us
I0717 14:27:40.552541 8663 master.cpp:1034] Attempting to re-register slave 201307171427-982172483-41892-8635-0 at slave(100)@67.195.138.58:41892 (quirinus.apache.org)
I0717 14:27:40.552781 8663 master.cpp:1929] Adding slave 201307171427-982172483-41892-8635-0 at quirinus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21163
I0717 14:27:40.553261 8665 slave.cpp:630] Re-registered with master master@67.195.138.58:41892
I0717 14:27:40.553355 8663 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
W0717 14:27:40.554219 8663 slave.cpp:1273] Ignoring updating pid for framework 201307171427-982172483-41892-8635-0000 because it is terminating
I0717 14:27:40.554342 8665 hierarchical_allocator_process.hpp:448] Added slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21163 (and cpus=1; mem=524; ports=[31000-32000]; disk=21163 available)
I0717 14:27:40.555438 8665 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0 to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.555989 8665 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307171427-982172483-41892-8635-0 in 574.005us
I0717 14:27:40.556073 8661 master.hpp:327] Adding offer 201307171427-982172483-41892-8635-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:40.556902 8661 master.cpp:1310] Sending 1 offers to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.557668 8660 sched.cpp:340] Scheduler::resourceOffers took 38.854us
I0717 14:27:40.557868 8660 sched.cpp:498] Stopping framework '201307171427-982172483-41892-8635-0000'
I0717 14:27:40.557881 8635 master.cpp:436] Master terminating
I0717 14:27:40.558779 8635 master.cpp:209] Shutting down master
I0717 14:27:40.559314 8635 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:40.559901 8635 master.hpp:337] Removing offer 201307171427-982172483-41892-8635-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:40.559440 8664 hierarchical_allocator_process.hpp:411] Deactivated framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.558804 8666 slave.cpp:485] Slave asked to shut down by master@67.195.138.58:41892
I0717 14:27:40.591827 8666 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by master@67.195.138.58:41892
I0717 14:27:40.591148 8664 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307171427-982172483-41892-8635-0 from framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.612754 8664 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=21163 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21163) on slave 201307171427-982172483-41892-8635-0 from framework 201307171427-982172483-41892-8635-0000
W0717 14:27:40.612341 8666 slave.cpp:1133] Ignoring shutdown framework 201307171427-982172483-41892-8635-0000 because it is terminating
I0717 14:27:40.612946 8661 detector.cpp:420] Master detector (slave(100)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:40.614536 8661 detector.cpp:441] Master detector (slave(100)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:40.613670 8666 slave.cpp:440] Slave terminating
I0717 14:27:40.613209 8664 hierarchical_allocator_process.hpp:366] Removed framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.615978 8664 hierarchical_allocator_process.hpp:476] Removed slave 201307171427-982172483-41892-8635-0
I0717 14:27:40.615465 8666 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by @0.0.0.0:0
W0717 14:27:40.616881 8666 slave.cpp:1133] Ignoring shutdown framework 201307171427-982172483-41892-8635-0000 because it is terminating
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1305 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0717 14:27:40.619303 8663 master.cpp:230] Master started on 67.195.138.58:41892
I0717 14:27:40.619356 8663 master.cpp:245] Master ID: 201307171427-982172483-41892-8635
I0717 14:27:40.619400 8664 slave.cpp:113] Slave started on 101)@67.195.138.58:41892
I0717 14:27:40.649569 8660 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.58:41892
W0717 14:27:40.649582 8662 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0717 14:27:40.650217 8664 slave.cpp:205] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=21163
I0717 14:27:40.670888 8661 detector.cpp:234] Master detector (master@67.195.138.58:41892) connected to ZooKeeper ...
I0717 14:27:40.672269 8661 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0717 14:27:40.671808 8667 detector.cpp:234] Master detector (scheduler(94)@67.195.138.58:41892) connected to ZooKeeper ...
I0717 14:27:40.671417 8665 detector.cpp:234] Master detector (slave(101)@67.195.138.58:41892) connected to ZooKeeper ...
I0717 14:27:40.673651 8665 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0717 14:27:40.673182 8667 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0717 14:27:40.672533 8664 slave.cpp:390] Finished recovery
I0717 14:27:40.703776 8661 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0717 14:27:40.704579 8665 detector.cpp:420] Master detector (slave(101)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:40.704892 8667 detector.cpp:420] Master detector (scheduler(94)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:40.704936 8661 detector.cpp:420] Master detector (master@67.195.138.58:41892) found 1 registered masters
I0717 14:27:40.705240 8665 detector.cpp:467] Master detector (slave(101)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:40.706164 8665 slave.cpp:529] New master detected at master@67.195.138.58:41892
I0717 14:27:40.705626 8667 detector.cpp:467] Master detector (scheduler(94)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:40.706204 8661 detector.cpp:467] Master detector (master@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:40.706629 8664 status_update_manager.cpp:155] New master detected at master@67.195.138.58:41892
W0717 14:27:40.706657 8660 master.cpp:943] Ignoring register slave message from quirinus.apache.org since not elected yet
I0717 14:27:40.708582 8660 master.cpp:583] Elected as master!
I0717 14:27:40.707092 8667 sched.cpp:178] New master at master@67.195.138.58:41892
I0717 14:27:40.709992 8666 master.cpp:633] Registering framework 201307171427-982172483-41892-8635-0000 at scheduler(94)@67.195.138.58:41892
I0717 14:27:40.710391 8665 sched.cpp:237] Framework registered with 201307171427-982172483-41892-8635-0000
I0717 14:27:40.710820 8665 sched.cpp:251] Scheduler::registered took 15.074us
I0717 14:27:40.710425 8666 hierarchical_allocator_process.hpp:335] Added framework 201307171427-982172483-41892-8635-0000
I0717 14:27:40.711751 8666 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0717 14:27:40.712224 8666 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 475.093us
I0717 14:27:41.670819 8662 hierarchical_allocator_process.hpp:704] No resources available to allocate!
I0717 14:27:41.680951 8662 hierarchical_allocator_process.hpp:666] Performed allocation for 0 slaves in 10.152337ms
I0717 14:27:41.707013 8661 master.cpp:962] Attempting to register slave on quirinus.apache.org at slave(101)@67.195.138.58:41892
I0717 14:27:41.707062 8661 master.cpp:1929] Adding slave 201307171427-982172483-41892-8635-0 at quirinus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21163
I0717 14:27:41.707476 8667 slave.cpp:589] Registered with master master@67.195.138.58:41892; given slave ID 201307171427-982172483-41892-8635-0
I0717 14:27:41.707622 8663 hierarchical_allocator_process.hpp:448] Added slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21163 (and cpus=2; mem=1024; ports=[31000-32000]; disk=21163 available)
I0717 14:27:41.708364 8663 hierarchical_allocator_process.hpp:726] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0 to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.708909 8663 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307171427-982172483-41892-8635-0 in 568.171us
I0717 14:27:41.708962 8667 master.hpp:327] Adding offer 201307171427-982172483-41892-8635-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.709844 8667 master.cpp:1310] Sending 1 offers to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.710536 8661 sched.cpp:340] Scheduler::resourceOffers took 89.714us
I0717 14:27:41.710918 8661 master.cpp:1543] Processing reply for offer 201307171427-982172483-41892-8635-0 on slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org) for framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.711344 8661 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.711782 8661 master.cpp:1662] Launching task 0 of framework 201307171427-982172483-41892-8635-0000 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org)
I0717 14:27:41.712344 8661 master.hpp:337] Removing offer 201307171427-982172483-41892-8635-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.712383 8667 slave.cpp:739] Got assigned task 0 for framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.712399 8660 hierarchical_allocator_process.hpp:525] Framework 201307171427-982172483-41892-8635-0000 left cpus=1; mem=524; ports=[31000-32000]; disk=21163 unused on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.713786 8660 hierarchical_allocator_process.hpp:568] Framework 201307171427-982172483-41892-8635-0000 filtered slave 201307171427-982172483-41892-8635-0 for 5secs
I0717 14:27:41.713438 8667 slave.cpp:837] Launching task 0 for framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.716024 8667 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_fT3Edo/slaves/201307171427-982172483-41892-8635-0/frameworks/201307171427-982172483-41892-8635-0000/executors/default/runs/33715a86-46d3-4703-b9f1-9f835075f444'
I0717 14:27:41.716346 8667 slave.cpp:948] Queuing task '0' for executor default of framework '201307171427-982172483-41892-8635-0000
I0717 14:27:41.716497 8663 exec.cpp:171] Executor started at: executor(38)@67.195.138.58:41892 with pid 8635
I0717 14:27:41.716604 8667 slave.cpp:511] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_fT3Edo/slaves/201307171427-982172483-41892-8635-0/frameworks/201307171427-982172483-41892-8635-0000/executors/default/runs/33715a86-46d3-4703-b9f1-9f835075f444'
I0717 14:27:41.717555 8667 slave.cpp:1395] Got registration for executor 'default' of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.718024 8667 slave.cpp:1510] Flushing queued task 0 for executor 'default' of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.718055 8662 exec.cpp:195] Executor registered on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.718955 8662 exec.cpp:206] Executor::registered took 12.048us
I0717 14:27:41.719713 8662 exec.cpp:274] Executor asked to run task '0'
I0717 14:27:41.720883 8662 exec.cpp:283] Executor::launchTask took 28.031us
I0717 14:27:41.722389 8662 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.722461 8662 slave.cpp:1692] Handling status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000 from executor(38)@67.195.138.58:41892
I0717 14:27:41.722892 8662 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000 with checkpoint=false
I0717 14:27:41.723316 8662 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.723839 8662 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000 to master@67.195.138.58:41892
I0717 14:27:41.724329 8662 slave.cpp:1803] Status update manager successfully handled status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.724341 8666 master.cpp:1093] Status update from slave(101)@67.195.138.58:41892: task 0 of framework 201307171427-982172483-41892-8635-0000 is now in state TASK_RUNNING
I0717 14:27:41.724781 8662 slave.cpp:1809] Sending acknowledgement for status update TASK_RUNNING (UUID: 4a057ca8-56e4-4f75-ba08-cbb089a2881f) for task 0 of framework 201307171427-982172483-41892-8635-0000 to executor(38)@67.195.138.58:41892
I0717 14:27:41.725375 8666 sched.cpp:395] Scheduler::statusUpdate took 31.229us
I0717 14:27:41.725847 8635 master.cpp:436] Master terminating
I0717 14:27:41.726868 8635 master.cpp:209] Shutting down master
I0717 14:27:41.726512 8666 status_update_manager.cpp:360] Received status update acknowledgement 4a057ca8-56e4-4f75-ba08-cbb089a2881f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.725942 8662 exec.cpp:320] Executor received status update acknowledgement 4a057ca8-56e4-4f75-ba08-cbb089a2881f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.726943 8665 slave.cpp:1884] master@67.195.138.58:41892 exited
W0717 14:27:41.729019 8665 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0717 14:27:41.729585 8665 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by master@67.195.138.58:41892
I0717 14:27:41.727360 8635 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:41.727406 8660 hierarchical_allocator_process.hpp:411] Deactivated framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.730481 8665 slave.cpp:1137] Shutting down framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.732069 8661 master.cpp:230] Master started on 67.195.138.58:41892
I0717 14:27:41.732831 8665 slave.cpp:2328] Shutting down executor 'default' of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.733840 8661 master.cpp:245] Master ID: 201307171427-982172483-41892-8635
W0717 14:27:41.760642 8662 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0717 14:27:41.760052 8665 slave.cpp:1343] Status update manager successfully handled status update acknowledgement 4a057ca8-56e4-4f75-ba08-cbb089a2881f for task 0 of framework 201307171427-982172483-41892-8635-0000
I0717 14:27:41.760032 8666 exec.cpp:360] Executor asked to shutdown
I0717 14:27:41.790791 8666 exec.cpp:375] Executor::shutdown took 12.005us
I0717 14:27:41.760848 8667 hierarchical_allocator_process.hpp:305] Initializing hierarchical allocator process with master : master@67.195.138.58:41892
I0717 14:27:41.790866 8663 detector.cpp:234] Master detector (master@67.195.138.58:41892) connected to ZooKeeper ...
I0717 14:27:41.792199 8663 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0717 14:27:41.791074 8664 detector.cpp:420] Master detector (scheduler(94)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:41.793186 8664 detector.cpp:441] Master detector (scheduler(94)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:41.790953 8660 detector.cpp:420] Master detector (slave(101)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:41.793678 8665 sched.cpp:202] No master detected, waiting for another master
I0717 14:27:41.794169 8660 detector.cpp:441] Master detector (slave(101)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:41.795007 8665 sched.cpp:214] Scheduler::disconnected took 9.968us
I0717 14:27:41.809964 8664 slave.cpp:563] Lost master(s) ... waiting
I0717 14:27:41.810041 8663 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0717 14:27:41.810729 8660 detector.cpp:420] Master detector (slave(101)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:41.810729 8667 detector.cpp:420] Master detector (scheduler(94)@67.195.138.58:41892) found 1 registered masters
I0717 14:27:41.811736 8663 detector.cpp:420] Master detector (master@67.195.138.58:41892) found 1 registered masters
I0717 14:27:41.812248 8660 detector.cpp:467] Master detector (slave(101)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:41.813323 8660 slave.cpp:529] New master detected at master@67.195.138.58:41892
I0717 14:27:41.813782 8662 status_update_manager.cpp:155] New master detected at master@67.195.138.58:41892
I0717 14:27:41.812742 8667 detector.cpp:467] Master detector (scheduler(94)@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:41.814748 8667 sched.cpp:178] New master at master@67.195.138.58:41892
W0717 14:27:41.813863 8665 master.cpp:989] Ignoring re-register slave message from quirinus.apache.org since not elected yet
I0717 14:27:41.813204 8663 detector.cpp:467] Master detector (master@67.195.138.58:41892) got new master pid: master@67.195.138.58:41892
I0717 14:27:41.816637 8663 master.cpp:583] Elected as master!
I0717 14:27:42.707394 8666 master.cpp:1034] Attempting to re-register slave 201307171427-982172483-41892-8635-0 at slave(101)@67.195.138.58:41892 (quirinus.apache.org)
I0717 14:27:42.713742 8666 master.cpp:1929] Adding slave 201307171427-982172483-41892-8635-0 at quirinus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21163
I0717 14:27:42.714248 8660 slave.cpp:630] Re-registered with master master@67.195.138.58:41892
I0717 14:27:42.714279 8666 master.hpp:305] Adding task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
W0717 14:27:42.715062 8666 master.cpp:2021] Possibly orphaned task 0 of framework 201307171427-982172483-41892-8635-0000 running on slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org)
I0717 14:27:42.715849 8666 hierarchical_allocator_process.hpp:448] Added slave 201307171427-982172483-41892-8635-0 (quirinus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21163 (and cpus=1; mem=524; ports=[31000-32000]; disk=21163 available)
I0717 14:27:42.716491 8666 hierarchical_allocator_process.hpp:686] Performed allocation for slave 201307171427-982172483-41892-8635-0 in 9.669us
I0717 14:27:42.792376 8667 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 11.949us
I0717 14:27:42.815594 8663 master.cpp:675] Re-registering framework 201307171427-982172483-41892-8635-0000 at scheduler(94)@67.195.138.58:41892
I0717 14:27:42.815757 8662 sched.cpp:268] Framework re-registered with 201307171427-982172483-41892-8635-0000
W0717 14:27:42.815799 8663 slave.cpp:1273] Ignoring updating pid for framework 201307171427-982172483-41892-8635-0000 because it is terminating
I0717 14:27:42.815855 8665 hierarchical_allocator_process.hpp:335] Added framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.816012 8662 sched.cpp:282] Scheduler::reregistered took 15.281us
I0717 14:27:42.817322 8665 hierarchical_allocator_process.hpp:726] Offering cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0 to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.818403 8665 hierarchical_allocator_process.hpp:666] Performed allocation for 1 slaves in 1.093562ms
I0717 14:27:42.818454 8664 master.hpp:327] Adding offer 201307171427-982172483-41892-8635-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:42.819298 8664 master.cpp:1310] Sending 1 offers to framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.819871 8661 sched.cpp:340] Scheduler::resourceOffers took 25.153us
I0717 14:27:42.820086 8635 master.cpp:436] Master terminating
I0717 14:27:42.820742 8635 master.cpp:209] Shutting down master
I0717 14:27:42.820271 8661 sched.cpp:498] Stopping framework '201307171427-982172483-41892-8635-0000'
I0717 14:27:42.820744 8667 slave.cpp:485] Slave asked to shut down by master@67.195.138.58:41892
I0717 14:27:42.821254 8665 hierarchical_allocator_process.hpp:411] Deactivated framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.821254 8635 master.hpp:317] Removing task 0 with resources cpus=1; mem=500 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:42.822142 8667 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by master@67.195.138.58:41892
W0717 14:27:42.848100 8667 slave.cpp:1133] Ignoring shutdown framework 201307171427-982172483-41892-8635-0000 because it is terminating
I0717 14:27:42.823725 8664 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; disk=0) on slave 201307171427-982172483-41892-8635-0 from framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.823707 8635 master.hpp:337] Removing offer 201307171427-982172483-41892-8635-0 with resources cpus=1; mem=524; ports=[31000-32000]; disk=21163 on slave 201307171427-982172483-41892-8635-0
I0717 14:27:42.848588 8667 slave.cpp:1884] master@67.195.138.58:41892 exited
W0717 14:27:42.850401 8667 slave.cpp:1887] Master disconnected! Waiting for a new master to be elected
I0717 14:27:42.851132 8667 slave.cpp:440] Slave terminating
I0717 14:27:42.852251 8667 slave.cpp:1112] Asked to shut down framework 201307171427-982172483-41892-8635-0000 by @0.0.0.0:0
W0717 14:27:42.881408 8667 slave.cpp:1133] Ignoring shutdown framework 201307171427-982172483-41892-8635-0000 because it is terminating
I0717 14:27:42.849504 8664 hierarchical_allocator_process.hpp:615] Recovered cpus=1; mem=524; ports=[31000-32000]; disk=21163 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21163) on slave 201307171427-982172483-41892-8635-0 from framework 201307171427-982172483-41892-8635-0000
I0717 14:27:42.881893 8661 detector.cpp:420] Master detector (slave(101)@67.195.138.58:41892) found 0 registered masters
I0717 14:27:42.883213 8661 detector.cpp:441] Master detector (slave(101)@67.195.138.58:41892) couldn't find any masters
I0717 14:27:42.882827 8664 hierarchical_allocator_process.hpp:366] Removed framework 201307171427-982172483-41892-8635-0000
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2266 ms)
I0717 14:27:42.901114 8635 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 41805
[----------] 2 tests from AllocatorZooKeeperTest/0 (3571 ms total)
[----------] Global test environment tear-down
[==========] 198 tests from 37 test cases ran. (167981 ms total)
[ PASSED ] 197 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] LevelDBStateTest.FetchAndStoreAndFetch
1 FAILED TEST
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Process leaked file descriptors. See http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+build for more information
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1189
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1189/>