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/03 21:55:55 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1357
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1357/changes>
Changes:
[reallen] Preserved newlines for copy/paste in Firefox
------------------------------------------
[...truncated 16874 lines...]
I0903 19:55:50.470140 21957 slave.cpp:1556] Flushing queued task 0 for executor 'default' of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.470170 21962 exec.cpp:198] Executor registered on slave 201309031955-1015726915-41564-21598-0
I0903 19:55:50.472204 21962 exec.cpp:210] Executor::registered took 19.013us
I0903 19:55:50.472337 21962 exec.cpp:285] Executor asked to run task '0'
I0903 19:55:50.494281 21962 exec.cpp:294] Executor::launchTask took 29.705us
I0903 19:55:50.495765 21962 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.495914 21962 slave.cpp:1766] Handling status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000 from executor(40)@67.195.138.60:41564
I0903 19:55:50.496290 21958 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.496645 21958 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.497201 21958 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000 to master@67.195.138.60:41564
I0903 19:55:50.497678 21959 master.cpp:1205] Status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000 from slave(131)@67.195.138.60:41564
I0903 19:55:50.497774 21961 slave.cpp:1885] Status update manager successfully handled status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.498571 21961 slave.cpp:1891] Sending acknowledgement for status update TASK_RUNNING (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000 to executor(40)@67.195.138.60:41564
I0903 19:55:50.498407 21598 master.cpp:478] Master terminating
I0903 19:55:50.498174 21959 sched.cpp:395] Scheduler::statusUpdate took 15.488us
I0903 19:55:50.499130 21960 exec.cpp:331] Executor received status update acknowledgement d4f5e21f-2caf-425f-b95b-17e61bdd86bc for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.499584 21598 master.cpp:206] Shutting down master
I0903 19:55:50.499608 21956 slave.cpp:1966] master@67.195.138.60:41564 exited
W0903 19:55:50.501485 21956 slave.cpp:1969] Master disconnected! Waiting for a new master to be elected
I0903 19:55:50.500995 21598 master.hpp:329] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:50.502723 21956 status_update_manager.cpp:375] Received status update acknowledgement (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.503417 21598 master.cpp:234] Removing slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:50.503479 21957 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 201309031955-1015726915-41564-21598-0 from framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.505033 21963 slave.cpp:1381] Status update manager successfully handled status update acknowledgement (UUID: d4f5e21f-2caf-425f-b95b-17e61bdd86bc) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:50.506862 21958 master.cpp:262] Master started on 67.195.138.60:41564
I0903 19:55:50.507294 21958 master.cpp:277] Master ID: 201309031955-1015726915-41564-21598
W0903 19:55:50.508400 21961 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0903 19:55:50.508525 21957 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.60:41564
I0903 19:55:50.832459 21962 detector.cpp:234] Master detector (master@67.195.138.60:41564) connected to ZooKeeper ...
I0903 19:55:50.832852 21956 detector.cpp:420] Master detector (scheduler(113)@67.195.138.60:41564) found 0 registered masters
I0903 19:55:50.832892 21962 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0903 19:55:50.834005 21958 detector.cpp:420] Master detector (slave(131)@67.195.138.60:41564) found 0 registered masters
I0903 19:55:50.840600 21958 detector.cpp:441] Master detector (slave(131)@67.195.138.60:41564) couldn't find any masters
I0903 19:55:50.839731 21956 detector.cpp:441] Master detector (scheduler(113)@67.195.138.60:41564) couldn't find any masters
I0903 19:55:50.897701 21956 sched.cpp:202] No master detected, waiting for another master
I0903 19:55:50.904893 21956 sched.cpp:214] Scheduler::disconnected took 13.992us
I0903 19:55:50.842284 21958 slave.cpp:578] Lost master(s) ... waiting
I0903 19:55:50.909579 21962 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0903 19:55:50.909721 21963 detector.cpp:420] Master detector (slave(131)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:50.909723 21957 detector.cpp:420] Master detector (scheduler(113)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:50.910338 21962 detector.cpp:420] Master detector (master@67.195.138.60:41564) found 1 registered masters
I0903 19:55:50.949497 21963 detector.cpp:467] Master detector (slave(131)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:50.968942 21957 detector.cpp:467] Master detector (scheduler(113)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:51.026856 21957 sched.cpp:178] New master at master@67.195.138.60:41564
I0903 19:55:51.004945 21963 slave.cpp:544] New master detected at master@67.195.138.60:41564
I0903 19:55:50.999727 21962 detector.cpp:467] Master detector (master@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
W0903 19:55:51.035622 21957 master.cpp:714] Ignoring re-register framework message since not elected yet
I0903 19:55:51.076911 21961 status_update_manager.cpp:157] New master detected at master@67.195.138.60:41564
I0903 19:55:51.138010 21957 master.cpp:642] Elected as master!
I0903 19:55:51.509662 21959 hierarchical_allocator_process.hpp:687] No resources available to allocate!
I0903 19:55:51.509889 21959 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 231.32us
I0903 19:55:52.036324 21958 master.cpp:734] Re-registering framework 201309031955-1015726915-41564-21598-0000 at scheduler(113)@67.195.138.60:41564
I0903 19:55:52.036711 21960 sched.cpp:237] Framework registered with 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.037297 21960 sched.cpp:251] Scheduler::registered took 15.308us
I0903 19:55:52.036874 21958 hierarchical_allocator_process.hpp:321] Added framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.038236 21958 hierarchical_allocator_process.hpp:687] No resources available to allocate!
I0903 19:55:52.038635 21958 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 399.783us
I0903 19:55:52.077497 21957 master.cpp:1159] Attempting to re-register slave 201309031955-1015726915-41564-21598-0 at slave(131)@67.195.138.60:41564 (janus.apache.org)
I0903 19:55:52.077632 21957 master.cpp:2078] Adding slave 201309031955-1015726915-41564-21598-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0903 19:55:52.093613 21962 slave.cpp:645] Re-registered with master master@67.195.138.60:41564
I0903 19:55:52.093654 21957 master.hpp:316] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:52.150045 21957 slave.cpp:1327] Updating framework 201309031955-1015726915-41564-21598-0000 pid to scheduler(113)@67.195.138.60:41564
I0903 19:55:52.150113 21962 hierarchical_allocator_process.hpp:434] Added slave 201309031955-1015726915-41564-21598-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available)
I0903 19:55:52.218075 21962 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.252631 21962 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309031955-1015726915-41564-21598-0 in 34.595492ms
I0903 19:55:52.252699 21959 master.hpp:339] Adding offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:52.281708 21959 master.cpp:1439] Sending 1 offers to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.291971 21959 sched.cpp:340] Scheduler::resourceOffers took 31.64us
I0903 19:55:52.292388 21598 master.cpp:478] Master terminating
I0903 19:55:52.293498 21598 master.cpp:206] Shutting down master
I0903 19:55:52.293931 21598 master.hpp:329] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:52.293519 21961 slave.cpp:1966] master@67.195.138.60:41564 exited
W0903 19:55:52.294872 21961 slave.cpp:1969] Master disconnected! Waiting for a new master to be elected
I0903 19:55:52.294461 21598 master.hpp:349] Removing offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:52.294601 21962 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309031955-1015726915-41564-21598-0 from framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.292505 21959 sched.cpp:498] Stopping framework '201309031955-1015726915-41564-21598-0000'
I0903 19:55:52.296315 21598 master.cpp:234] Removing slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:52.337442 21598 slave.cpp:454] Slave terminating
I0903 19:55:52.342161 21598 slave.cpp:1162] Asked to shut down framework 201309031955-1015726915-41564-21598-0000 by @0.0.0.0:0
I0903 19:55:52.342519 21598 slave.cpp:1187] Shutting down framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.343011 21598 slave.cpp:2417] Shutting down executor 'default' of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.343554 21957 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2b57fc04c8a0)
Stack trace:
I0903 19:55:52.344535 21957 exec.cpp:386] Executor::shutdown took 32.351us
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (3435 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0903 19:55:52.345523 21960 master.cpp:262] Master started on 67.195.138.60:41564
I0903 19:55:52.345947 21960 master.cpp:277] Master ID: 201309031955-1015726915-41564-21598
I0903 19:55:52.346272 21963 slave.cpp:108] Slave started on 132)@67.195.138.60:41564
W0903 19:55:52.347121 21962 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0903 19:55:52.347142 21959 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.60:41564
I0903 19:55:52.369520 21963 slave.cpp:208] Slave resources: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0903 19:55:52.382174 21957 slave.cpp:399] Finished recovery
I0903 19:55:52.465587 21957 detector.cpp:234] Master detector (master@67.195.138.60:41564) connected to ZooKeeper ...
I0903 19:55:52.465674 21957 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0903 19:55:52.466199 21959 detector.cpp:234] Master detector (slave(132)@67.195.138.60:41564) connected to ZooKeeper ...
I0903 19:55:52.466686 21956 detector.cpp:234] Master detector (scheduler(114)@67.195.138.60:41564) connected to ZooKeeper ...
I0903 19:55:52.542306 21956 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0903 19:55:52.518501 21959 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0903 19:55:52.542628 21957 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0903 19:55:52.587792 21956 detector.cpp:420] Master detector (scheduler(114)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:52.614603 21959 detector.cpp:420] Master detector (slave(132)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:52.642964 21957 detector.cpp:420] Master detector (master@67.195.138.60:41564) found 1 registered masters
I0903 19:55:52.669858 21956 detector.cpp:467] Master detector (scheduler(114)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:52.697381 21959 detector.cpp:467] Master detector (slave(132)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:52.699882 21957 detector.cpp:467] Master detector (master@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:52.740900 21963 sched.cpp:178] New master at master@67.195.138.60:41564
I0903 19:55:52.762295 21959 slave.cpp:544] New master detected at master@67.195.138.60:41564
I0903 19:55:52.780032 21957 master.cpp:642] Elected as master!
I0903 19:55:52.818914 21961 status_update_manager.cpp:157] New master detected at master@67.195.138.60:41564
I0903 19:55:52.835572 21957 master.cpp:692] Registering framework 201309031955-1015726915-41564-21598-0000 at scheduler(114)@67.195.138.60:41564
I0903 19:55:52.857734 21957 master.cpp:1065] Attempting to register slave on janus.apache.org at slave(132)@67.195.138.60:41564
I0903 19:55:52.857750 21959 sched.cpp:237] Framework registered with 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.857828 21956 hierarchical_allocator_process.hpp:321] Added framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.859128 21956 hierarchical_allocator_process.hpp:687] No resources available to allocate!
I0903 19:55:52.860298 21956 hierarchical_allocator_process.hpp:649] Performed allocation for 0 slaves in 1.171968ms
I0903 19:55:52.857969 21957 master.cpp:2078] Adding slave 201309031955-1015726915-41564-21598-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0903 19:55:52.907683 21958 slave.cpp:604] Registered with master master@67.195.138.60:41564; given slave ID 201309031955-1015726915-41564-21598-0
I0903 19:55:52.858423 21959 sched.cpp:251] Scheduler::registered took 19.832us
I0903 19:55:52.907763 21962 hierarchical_allocator_process.hpp:434] Added slave 201309031955-1015726915-41564-21598-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available)
I0903 19:55:52.964663 21962 hierarchical_allocator_process.hpp:712] Offering cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:52.970916 21962 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309031955-1015726915-41564-21598-0 in 6.282659ms
I0903 19:55:52.971118 21958 master.hpp:339] Adding offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.057545 21958 master.cpp:1439] Sending 1 offers to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.085439 21958 sched.cpp:340] Scheduler::resourceOffers took 193.914us
I0903 19:55:53.101989 21960 master.cpp:1676] Processing reply for offer 201309031955-1015726915-41564-21598-0 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org) for framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.129861 21960 master.hpp:316] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.142619 21960 master.cpp:1796] Launching task 0 of framework 201309031955-1015726915-41564-21598-0000 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.157356 21958 slave.cpp:767] Got assigned task 0 for framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.157410 21961 hierarchical_allocator_process.hpp:508] Framework 201309031955-1015726915-41564-21598-0000 left cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] unused on slave 201309031955-1015726915-41564-21598-0
I0903 19:55:53.157436 21960 master.hpp:349] Removing offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.187434 21958 slave.cpp:878] Launching task 0 for framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.195982 21961 hierarchical_allocator_process.hpp:551] Framework 201309031955-1015726915-41564-21598-0000 filtered slave 201309031955-1015726915-41564-21598-0 for 5secs
I0903 19:55:53.255130 21958 paths.hpp:336] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_BjLMSM/slaves/201309031955-1015726915-41564-21598-0/frameworks/201309031955-1015726915-41564-21598-0000/executors/default/runs/124cf8d1-f62b-4deb-96c8-68babcc94ea1'
I0903 19:55:53.279271 21958 slave.cpp:989] Queuing task '0' for executor default of framework '201309031955-1015726915-41564-21598-0000
I0903 19:55:53.342872 21958 slave.cpp:526] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_BjLMSM/slaves/201309031955-1015726915-41564-21598-0/frameworks/201309031955-1015726915-41564-21598-0000/executors/default/runs/124cf8d1-f62b-4deb-96c8-68babcc94ea1'
I0903 19:55:53.281031 21961 exec.cpp:174] Executor started at: executor(41)@67.195.138.60:41564 with pid 21598
I0903 19:55:53.382189 21957 hierarchical_allocator_process.hpp:795] Filtered cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 for framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.413053 21961 slave.cpp:1435] Got registration for executor 'default' of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.434943 21957 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 52.876763ms
I0903 19:55:53.476285 21961 slave.cpp:1556] Flushing queued task 0 for executor 'default' of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.476304 21962 exec.cpp:198] Executor registered on slave 201309031955-1015726915-41564-21598-0
I0903 19:55:53.557829 21962 exec.cpp:210] Executor::registered took 20.615us
I0903 19:55:53.568047 21962 exec.cpp:285] Executor asked to run task '0'
I0903 19:55:53.609472 21962 exec.cpp:294] Executor::launchTask took 34.615us
I0903 19:55:53.618403 21962 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.696447 21962 slave.cpp:1766] Handling status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000 from executor(41)@67.195.138.60:41564
I0903 19:55:53.706217 21962 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.723073 21962 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.734254 21962 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000 to master@67.195.138.60:41564
I0903 19:55:53.745386 21962 master.cpp:1205] Status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000 from slave(132)@67.195.138.60:41564
I0903 19:55:53.745450 21959 slave.cpp:1885] Status update manager successfully handled status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.797790 21959 slave.cpp:1891] Sending acknowledgement for status update TASK_RUNNING (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000 to executor(41)@67.195.138.60:41564
I0903 19:55:53.817203 21959 exec.cpp:331] Executor received status update acknowledgement f7bdf974-7590-4d11-ae41-2a190cd706ce for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.783375 21962 sched.cpp:395] Scheduler::statusUpdate took 43.012us
I0903 19:55:53.849990 21962 status_update_manager.cpp:375] Received status update acknowledgement (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.783545 21598 master.cpp:478] Master terminating
I0903 19:55:53.904724 21598 master.cpp:206] Shutting down master
I0903 19:55:53.922811 21598 master.hpp:329] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.885095 21960 slave.cpp:1381] Status update manager successfully handled status update acknowledgement (UUID: f7bdf974-7590-4d11-ae41-2a190cd706ce) for task 0 of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.933964 21598 master.cpp:234] Removing slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:53.934031 21962 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 201309031955-1015726915-41564-21598-0 from framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:53.965278 21960 slave.cpp:1966] master@67.195.138.60:41564 exited
I0903 19:55:54.009785 21961 master.cpp:262] Master started on 67.195.138.60:41564
W0903 19:55:54.017943 21960 slave.cpp:1969] Master disconnected! Waiting for a new master to be elected
I0903 19:55:54.033794 21961 master.cpp:277] Master ID: 201309031955-1015726915-41564-21598
W0903 19:55:54.035486 21956 master.cpp:80] No whitelist given. Advertising offers for all slaves
I0903 19:55:54.035751 21962 hierarchical_allocator_process.hpp:291] Initializing hierarchical allocator process with master : master@67.195.138.60:41564
I0903 19:55:54.097712 21960 detector.cpp:234] Master detector (master@67.195.138.60:41564) connected to ZooKeeper ...
I0903 19:55:54.098017 21960 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0903 19:55:54.098505 21963 detector.cpp:420] Master detector (scheduler(114)@67.195.138.60:41564) found 0 registered masters
I0903 19:55:54.098489 21959 detector.cpp:420] Master detector (slave(132)@67.195.138.60:41564) found 0 registered masters
I0903 19:55:54.175636 21959 detector.cpp:441] Master detector (slave(132)@67.195.138.60:41564) couldn't find any masters
I0903 19:55:54.111923 21963 detector.cpp:441] Master detector (scheduler(114)@67.195.138.60:41564) couldn't find any masters
I0903 19:55:54.175900 21960 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0903 19:55:54.176049 21958 slave.cpp:578] Lost master(s) ... waiting
I0903 19:55:54.176748 21959 detector.cpp:420] Master detector (slave(132)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:54.207433 21960 detector.cpp:420] Master detector (master@67.195.138.60:41564) found 1 registered masters
I0903 19:55:54.207149 21963 detector.cpp:420] Master detector (scheduler(114)@67.195.138.60:41564) found 1 registered masters
I0903 19:55:54.206059 21962 sched.cpp:202] No master detected, waiting for another master
I0903 19:55:54.208207 21959 detector.cpp:467] Master detector (slave(132)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:54.208736 21962 sched.cpp:214] Scheduler::disconnected took 14.516us
I0903 19:55:54.212908 21960 detector.cpp:467] Master detector (master@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:54.213070 21959 slave.cpp:544] New master detected at master@67.195.138.60:41564
I0903 19:55:54.213151 21960 master.cpp:642] Elected as master!
I0903 19:55:54.213608 21963 detector.cpp:467] Master detector (scheduler(114)@67.195.138.60:41564) got new master pid: master@67.195.138.60:41564
I0903 19:55:54.222463 21962 status_update_manager.cpp:157] New master detected at master@67.195.138.60:41564
I0903 19:55:54.222897 21960 master.cpp:1159] Attempting to re-register slave 201309031955-1015726915-41564-21598-0 at slave(132)@67.195.138.60:41564 (janus.apache.org)
I0903 19:55:54.223305 21963 sched.cpp:178] New master at master@67.195.138.60:41564
I0903 19:55:54.224220 21960 master.cpp:2078] Adding slave 201309031955-1015726915-41564-21598-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0903 19:55:54.225232 21960 master.hpp:316] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:54.225312 21957 slave.cpp:645] Re-registered with master master@67.195.138.60:41564
W0903 19:55:54.225675 21960 master.cpp:2170] Possibly orphaned task 0 of framework 201309031955-1015726915-41564-21598-0000 running on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:54.233609 21963 hierarchical_allocator_process.hpp:434] Added slave 201309031955-1015726915-41564-21598-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available)
I0903 19:55:54.236568 21963 hierarchical_allocator_process.hpp:669] Performed allocation for slave 201309031955-1015726915-41564-21598-0 in 10.848us
I0903 19:55:55.049361 21959 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 39.85us
I0903 19:55:55.225772 21957 master.cpp:734] Re-registering framework 201309031955-1015726915-41564-21598-0000 at scheduler(114)@67.195.138.60:41564
I0903 19:55:55.226178 21957 slave.cpp:1327] Updating framework 201309031955-1015726915-41564-21598-0000 pid to scheduler(114)@67.195.138.60:41564
I0903 19:55:55.226174 21958 sched.cpp:237] Framework registered with 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.226330 21962 hierarchical_allocator_process.hpp:321] Added framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.226797 21958 sched.cpp:251] Scheduler::registered took 24.644us
I0903 19:55:55.227365 21962 hierarchical_allocator_process.hpp:712] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.228380 21962 hierarchical_allocator_process.hpp:649] Performed allocation for 1 slaves in 1.087217ms
I0903 19:55:55.228454 21956 master.hpp:339] Adding offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:55.229192 21956 master.cpp:1439] Sending 1 offers to framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.229868 21961 sched.cpp:340] Scheduler::resourceOffers took 49.621us
I0903 19:55:55.230146 21957 master.cpp:478] Master terminating
I0903 19:55:55.230203 21961 sched.cpp:498] Stopping framework '201309031955-1015726915-41564-21598-0000'
I0903 19:55:55.230625 21957 slave.cpp:1966] master@67.195.138.60:41564 exited
W0903 19:55:55.232364 21957 slave.cpp:1969] Master disconnected! Waiting for a new master to be elected
I0903 19:55:55.230662 21598 master.cpp:206] Shutting down master
I0903 19:55:55.233708 21598 master.hpp:329] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:55.247696 21598 master.hpp:349] Removing offer 201309031955-1015726915-41564-21598-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:55.247771 21958 hierarchical_allocator_process.hpp:598] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201309031955-1015726915-41564-21598-0 from framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.248034 21598 master.cpp:234] Removing slave 201309031955-1015726915-41564-21598-0 (janus.apache.org)
I0903 19:55:55.249661 21598 slave.cpp:454] Slave terminating
I0903 19:55:55.249729 21598 slave.cpp:1162] Asked to shut down framework 201309031955-1015726915-41564-21598-0000 by @0.0.0.0:0
I0903 19:55:55.250072 21598 slave.cpp:1187] Shutting down framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.250586 21598 slave.cpp:2417] Shutting down executor 'default' of framework 201309031955-1015726915-41564-21598-0000
I0903 19:55:55.254132 21959 exec.cpp:371] Executor asked to shutdown
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: shutdown(0x2b57e0030d30)
Stack trace:
I0903 19:55:55.265629 21959 exec.cpp:386] Executor::shutdown took 71.397us
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2921 ms)
I0903 19:55:55.277050 21598 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 42186
[----------] 2 tests from AllocatorZooKeeperTest/0 (6356 ms total)
[----------] Global test environment tear-down
[==========] 220 tests from 39 test cases ran. (318803 ms total)
[ PASSED ] 219 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.MultipleFrameworks, where TypeParam = mesos::internal::slave::ProcessIsolator
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
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1358
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1358/changes>