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/25 03:20:51 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1237
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1237/>
------------------------------------------
[...truncated 14811 lines...]
I0725 01:20:48.189230 18227 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a) for task 0 of framework 201307250120-453759884-47047-17871-0000 to master@140.211.11.27:47047
I0725 01:20:48.190042 18227 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a) for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.190093 18241 master.cpp:1093] Status update from slave(108)@140.211.11.27:47047: task 0 of framework 201307250120-453759884-47047-17871-0000 is now in state TASK_RUNNING
I0725 01:20:48.190737 18227 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a) for task 0 of framework 201307250120-453759884-47047-17871-0000 to executor(38)@140.211.11.27:47047
I0725 01:20:48.192618 17871 master.cpp:436] Master terminating
I0725 01:20:48.191617 18241 sched.cpp:395] Scheduler::statusUpdate took 29.795us
I0725 01:20:48.192852 17871 master.cpp:209] Shutting down master
I0725 01:20:48.193128 18231 exec.cpp:320] Executor received status update acknowledgement 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.193141 18227 slave.cpp:1912] master@140.211.11.27:47047 exited
W0725 01:20:48.195791 18227 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected
I0725 01:20:48.194212 18229 hierarchical_allocator_process.hpp:393] Deactivated framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.194169 17871 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
I0725 01:20:48.196972 18227 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by master@140.211.11.27:47047
I0725 01:20:48.199519 18227 slave.cpp:1165] Shutting down framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.198712 18224 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]) on slave 201307250120-453759884-47047-17871-0 from framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.197192 18222 status_update_manager.cpp:360] Received status update acknowledgement 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.200121 18227 slave.cpp:2356] Shutting down executor 'default' of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.202872 18223 master.cpp:230] Master started on 140.211.11.27:47047
I0725 01:20:48.203184 18238 exec.cpp:360] Executor asked to shutdown
I0725 01:20:48.203227 18227 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 03252db9-93ef-4d5d-ad3e-5cc3c2529c2a for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:48.205900 18240 detector.cpp:420] Master detector (slave(108)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:48.206017 18226 detector.cpp:420] Master detector (scheduler(99)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:48.216308 18226 detector.cpp:441] Master detector (scheduler(99)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:48.213719 18223 master.cpp:245] Master ID: 201307250120-453759884-47047-17871
I0725 01:20:48.214341 18238 exec.cpp:375] Executor::shutdown took 15.519us
I0725 01:20:48.215682 18240 detector.cpp:441] Master detector (slave(108)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:48.207936 18228 detector.cpp:234] Master detector (master@140.211.11.27:47047) connected to ZooKeeper ...
I0725 01:20:48.216867 18226 sched.cpp:202] No master detected, waiting for another master
W0725 01:20:48.217934 18236 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0725 01:20:48.218101 18225 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : master@140.211.11.27:47047
I0725 01:20:48.219204 18240 slave.cpp:571] Lost master(s) ... waiting
I0725 01:20:48.219956 18228 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0725 01:20:48.220625 18226 sched.cpp:214] Scheduler::disconnected took 16.175us
I0725 01:20:48.227329 18228 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000002'
I0725 01:20:48.228204 18233 detector.cpp:420] Master detector (scheduler(99)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:48.229045 18243 detector.cpp:420] Master detector (slave(108)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:48.229563 18228 detector.cpp:420] Master detector (master@140.211.11.27:47047) found 1 registered masters
I0725 01:20:48.234840 18233 detector.cpp:467] Master detector (scheduler(99)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:48.236186 18243 detector.cpp:467] Master detector (slave(108)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:48.236610 18233 sched.cpp:178] New master at master@140.211.11.27:47047
I0725 01:20:48.237216 18228 detector.cpp:467] Master detector (master@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:48.237635 18243 slave.cpp:537] New master detected at master@140.211.11.27:47047
W0725 01:20:48.238731 18229 master.cpp:655] Ignoring re-register framework message since not elected yet
I0725 01:20:48.240054 18226 status_update_manager.cpp:155] New master detected at master@140.211.11.27:47047
I0725 01:20:48.241680 18229 master.cpp:583] Elected as master!
I0725 01:20:49.160271 18242 master.cpp:675] Re-registering framework 201307250120-453759884-47047-17871-0000 at scheduler(99)@140.211.11.27:47047
I0725 01:20:49.161816 18242 sched.cpp:268] Framework re-registered with 201307250120-453759884-47047-17871-0000
I0725 01:20:49.162034 18227 hierarchical_allocator_process.hpp:317] Added framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.162966 18242 sched.cpp:282] Scheduler::reregistered took 13.673us
I0725 01:20:49.163934 18227 hierarchical_allocator_process.hpp:684] No resources available to allocate!
I0725 01:20:49.165915 18227 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 2.000357ms
I0725 01:20:49.223330 18243 hierarchical_allocator_process.hpp:684] No resources available to allocate!
I0725 01:20:49.224522 18243 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 1.202702ms
I0725 01:20:49.240808 18225 master.cpp:1034] Attempting to re-register slave 201307250120-453759884-47047-17871-0 at slave(108)@140.211.11.27:47047 (hemera.apache.org)
I0725 01:20:49.242105 18225 master.cpp:1941] Adding slave 201307250120-453759884-47047-17871-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]
I0725 01:20:49.242986 18227 slave.cpp:638] Re-registered with master master@140.211.11.27:47047
I0725 01:20:49.242982 18225 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
W0725 01:20:49.245214 18227 slave.cpp:1301] Ignoring updating pid for framework 201307250120-453759884-47047-17871-0000 because it is terminating
I0725 01:20:49.245308 18243 hierarchical_allocator_process.hpp:430] Added slave 201307250120-453759884-47047-17871-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] available)
I0725 01:20:49.247161 18243 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0 to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.247935 18243 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307250120-453759884-47047-17871-0 in 807.023us
I0725 01:20:49.248044 18225 master.hpp:328] Adding offer 201307250120-453759884-47047-17871-0 with resources cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:49.250171 18225 master.cpp:1322] Sending 1 offers to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.250998 18225 sched.cpp:340] Scheduler::resourceOffers took 33.111us
I0725 01:20:49.251369 17871 master.cpp:436] Master terminating
I0725 01:20:49.251387 18224 sched.cpp:498] Stopping framework '201307250120-453759884-47047-17871-0000'
I0725 01:20:49.252322 17871 master.cpp:209] Shutting down master
I0725 01:20:49.252331 18244 slave.cpp:493] Slave asked to shut down by master@140.211.11.27:47047
I0725 01:20:49.254799 18244 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by master@140.211.11.27:47047
W0725 01:20:49.255484 18244 slave.cpp:1161] Ignoring shutdown framework 201307250120-453759884-47047-17871-0000 because it is terminating
I0725 01:20:49.261883 18244 slave.cpp:1912] master@140.211.11.27:47047 exited
W0725 01:20:49.262797 18244 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected
I0725 01:20:49.264149 18244 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by master@140.211.11.27:47047
W0725 01:20:49.264858 18244 slave.cpp:1161] Ignoring shutdown framework 201307250120-453759884-47047-17871-0000 because it is terminating
I0725 01:20:49.254021 17871 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
I0725 01:20:49.254034 18236 hierarchical_allocator_process.hpp:393] Deactivated framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.267382 17871 master.hpp:338] Removing offer 201307250120-453759884-47047-17871-0 with resources cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:49.268270 18236 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307250120-453759884-47047-17871-0 from framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.269683 18236 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]) on slave 201307250120-453759884-47047-17871-0 from framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.270094 18236 hierarchical_allocator_process.hpp:348] Removed framework 201307250120-453759884-47047-17871-0000
I0725 01:20:49.271154 18236 hierarchical_allocator_process.hpp:456] Removed slave 201307250120-453759884-47047-17871-0
I0725 01:20:49.271229 18235 slave.cpp:448] Slave terminating
I0725 01:20:49.271818 18222 detector.cpp:420] Master detector (slave(108)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:49.278311 18222 detector.cpp:441] Master detector (slave(108)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:49.277544 18235 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by @0.0.0.0:0
W0725 01:20:49.279855 18235 slave.cpp:1161] Ignoring shutdown framework 201307250120-453759884-47047-17871-0000 because it is terminating
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (2154 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
I0725 01:20:49.283488 18223 master.cpp:230] Master started on 140.211.11.27:47047
I0725 01:20:49.283623 18223 master.cpp:245] Master ID: 201307250120-453759884-47047-17871
W0725 01:20:49.284142 18241 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0725 01:20:49.284188 18230 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : master@140.211.11.27:47047
I0725 01:20:49.285126 18239 slave.cpp:113] Slave started on 109)@140.211.11.27:47047
I0725 01:20:49.285442 18239 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]
I0725 01:20:49.286509 18221 slave.cpp:398] Finished recovery
I0725 01:20:49.286811 18239 detector.cpp:234] Master detector (master@140.211.11.27:47047) connected to ZooKeeper ...
I0725 01:20:49.293623 18239 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0725 01:20:49.288740 18241 detector.cpp:234] Master detector (scheduler(100)@140.211.11.27:47047) connected to ZooKeeper ...
I0725 01:20:49.287750 18231 detector.cpp:234] Master detector (slave(109)@140.211.11.27:47047) connected to ZooKeeper ...
I0725 01:20:49.295474 18241 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0725 01:20:49.295928 18231 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0725 01:20:49.297308 18239 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004'
I0725 01:20:49.298969 18241 detector.cpp:420] Master detector (scheduler(100)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:49.299280 18231 detector.cpp:420] Master detector (slave(109)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:49.299823 18239 detector.cpp:420] Master detector (master@140.211.11.27:47047) found 1 registered masters
I0725 01:20:49.302546 18241 detector.cpp:467] Master detector (scheduler(100)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:49.303984 18231 detector.cpp:467] Master detector (slave(109)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:49.304473 18235 sched.cpp:178] New master at master@140.211.11.27:47047
I0725 01:20:49.304785 18239 detector.cpp:467] Master detector (master@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:49.304967 18231 slave.cpp:537] New master detected at master@140.211.11.27:47047
W0725 01:20:49.305572 18235 master.cpp:606] Ignoring register framework message since not elected yet
I0725 01:20:49.306768 18231 status_update_manager.cpp:155] New master detected at master@140.211.11.27:47047
I0725 01:20:49.307519 18235 master.cpp:583] Elected as master!
I0725 01:20:49.308310 18235 master.cpp:962] Attempting to register slave on hemera.apache.org at slave(109)@140.211.11.27:47047
I0725 01:20:49.308701 18235 master.cpp:1941] Adding slave 201307250120-453759884-47047-17871-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]
I0725 01:20:49.309393 18242 slave.cpp:597] Registered with master master@140.211.11.27:47047; given slave ID 201307250120-453759884-47047-17871-0
I0725 01:20:49.309476 18235 hierarchical_allocator_process.hpp:430] Added slave 201307250120-453759884-47047-17871-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] available)
I0725 01:20:49.311303 18235 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307250120-453759884-47047-17871-0 in 8.932us
I0725 01:20:50.285641 18242 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 55.014us
I0725 01:20:50.306885 18236 master.cpp:633] Registering framework 201307250120-453759884-47047-17871-0000 at scheduler(100)@140.211.11.27:47047
I0725 01:20:50.308542 18236 hierarchical_allocator_process.hpp:317] Added framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.308548 18242 sched.cpp:237] Framework registered with 201307250120-453759884-47047-17871-0000
I0725 01:20:50.309883 18236 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0 to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.310782 18242 sched.cpp:251] Scheduler::registered took 20.136us
I0725 01:20:50.311756 18236 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 1.975018ms
I0725 01:20:50.311928 18228 master.hpp:328] Adding offer 201307250120-453759884-47047-17871-0 with resources cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.314636 18228 master.cpp:1322] Sending 1 offers to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.315798 18228 sched.cpp:340] Scheduler::resourceOffers took 341.954us
I0725 01:20:50.316640 18228 master.cpp:1555] Processing reply for offer 201307250120-453759884-47047-17871-0 on slave 201307250120-453759884-47047-17871-0 (hemera.apache.org) for framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.317692 18228 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.318459 18228 master.cpp:1674] Launching task 0 of framework 201307250120-453759884-47047-17871-0000 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0 (hemera.apache.org)
I0725 01:20:50.319216 18228 master.hpp:338] Removing offer 201307250120-453759884-47047-17871-0 with resources cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.319254 18244 slave.cpp:747] Got assigned task 0 for framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.319262 18222 hierarchical_allocator_process.hpp:505] Framework 201307250120-453759884-47047-17871-0000 left cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] unused on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.321159 18244 slave.cpp:845] Launching task 0 for framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.322029 18222 hierarchical_allocator_process.hpp:548] Framework 201307250120-453759884-47047-17871-0000 filtered slave 201307250120-453759884-47047-17871-0 for 5secs
I0725 01:20:50.325108 18244 paths.hpp:303] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_ugdKy3/slaves/201307250120-453759884-47047-17871-0/frameworks/201307250120-453759884-47047-17871-0000/executors/default/runs/394c570c-30f9-491b-baf0-ed264ecc31dc'
I0725 01:20:50.325968 18244 slave.cpp:956] Queuing task '0' for executor default of framework '201307250120-453759884-47047-17871-0000
I0725 01:20:50.326359 18227 exec.cpp:171] Executor started at: executor(39)@140.211.11.27:47047 with pid 17871
I0725 01:20:50.326926 18244 slave.cpp:519] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_ugdKy3/slaves/201307250120-453759884-47047-17871-0/frameworks/201307250120-453759884-47047-17871-0000/executors/default/runs/394c570c-30f9-491b-baf0-ed264ecc31dc'
I0725 01:20:50.328419 18244 slave.cpp:1423] Got registration for executor 'default' of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.329648 18244 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.329747 18238 exec.cpp:195] Executor registered on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.330991 18238 exec.cpp:206] Executor::registered took 16.019us
I0725 01:20:50.331707 18238 exec.cpp:274] Executor asked to run task '0'
I0725 01:20:50.332386 18238 exec.cpp:283] Executor::launchTask took 29.302us
I0725 01:20:50.335211 18238 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.335943 18238 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000 from executor(39)@140.211.11.27:47047
I0725 01:20:50.336875 18238 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000 with checkpoint=false
I0725 01:20:50.337915 18238 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.338682 18238 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000 to master@140.211.11.27:47047
I0725 01:20:50.339449 18238 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.340230 18238 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: b3375869-e12b-43f5-83d3-3a71e5d2e815) for task 0 of framework 201307250120-453759884-47047-17871-0000 to executor(39)@140.211.11.27:47047
I0725 01:20:50.339478 18229 master.cpp:1093] Status update from slave(109)@140.211.11.27:47047: task 0 of framework 201307250120-453759884-47047-17871-0000 is now in state TASK_RUNNING
I0725 01:20:50.340932 18234 exec.cpp:320] Executor received status update acknowledgement b3375869-e12b-43f5-83d3-3a71e5d2e815 for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.342735 17871 master.cpp:436] Master terminating
I0725 01:20:50.342005 18229 sched.cpp:395] Scheduler::statusUpdate took 62.69us
I0725 01:20:50.343533 17871 master.cpp:209] Shutting down master
I0725 01:20:50.343580 18231 slave.cpp:1912] master@140.211.11.27:47047 exited
I0725 01:20:50.345310 17871 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
I0725 01:20:50.345381 18240 hierarchical_allocator_process.hpp:393] Deactivated framework 201307250120-453759884-47047-17871-0000
W0725 01:20:50.346005 18231 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected
I0725 01:20:50.349154 18231 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by master@140.211.11.27:47047
I0725 01:20:50.349652 18231 slave.cpp:1165] Shutting down framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.357643 18231 slave.cpp:2356] Shutting down executor 'default' of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.349213 18244 status_update_manager.cpp:360] Received status update acknowledgement b3375869-e12b-43f5-83d3-3a71e5d2e815 for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.350709 18235 detector.cpp:420] Master detector (scheduler(100)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:50.350878 18239 detector.cpp:420] Master detector (slave(109)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:50.352591 18241 detector.cpp:234] Master detector (master@140.211.11.27:47047) connected to ZooKeeper ...
I0725 01:20:50.361547 18241 detector.cpp:251] Trying to create path '/znode' in ZooKeeper
I0725 01:20:50.358361 18231 exec.cpp:360] Executor asked to shutdown
I0725 01:20:50.363535 18231 exec.cpp:375] Executor::shutdown took 15.866us
I0725 01:20:50.360095 18235 detector.cpp:441] Master detector (scheduler(100)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:50.360856 18239 detector.cpp:441] Master detector (slave(109)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:50.349480 18242 master.cpp:230] Master started on 140.211.11.27:47047
I0725 01:20:50.359262 18225 slave.cpp:1371] Status update manager successfully handled status update acknowledgement b3375869-e12b-43f5-83d3-3a71e5d2e815 for task 0 of framework 201307250120-453759884-47047-17871-0000
I0725 01:20:50.364788 18235 sched.cpp:202] No master detected, waiting for another master
I0725 01:20:50.365556 18241 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006'
I0725 01:20:50.366436 18223 detector.cpp:420] Master detector (scheduler(100)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:50.366745 18239 detector.cpp:420] Master detector (slave(109)@140.211.11.27:47047) found 1 registered masters
I0725 01:20:50.369488 18242 master.cpp:245] Master ID: 201307250120-453759884-47047-17871
I0725 01:20:50.370220 18225 slave.cpp:571] Lost master(s) ... waiting
I0725 01:20:50.370908 18235 sched.cpp:214] Scheduler::disconnected took 47.92us
I0725 01:20:50.373170 18241 detector.cpp:420] Master detector (master@140.211.11.27:47047) found 1 registered masters
I0725 01:20:50.373571 18223 detector.cpp:467] Master detector (scheduler(100)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:50.374152 18239 detector.cpp:467] Master detector (slave(109)@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:50.377218 18241 detector.cpp:467] Master detector (master@140.211.11.27:47047) got new master pid: master@140.211.11.27:47047
I0725 01:20:50.374372 18221 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : master@140.211.11.27:47047
I0725 01:20:50.377002 18231 sched.cpp:178] New master at master@140.211.11.27:47047
W0725 01:20:50.374239 18243 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0725 01:20:50.377410 18227 slave.cpp:537] New master detected at master@140.211.11.27:47047
I0725 01:20:50.377832 18241 master.cpp:583] Elected as master!
I0725 01:20:50.380938 18239 status_update_manager.cpp:155] New master detected at master@140.211.11.27:47047
I0725 01:20:50.381723 18241 master.cpp:1034] Attempting to re-register slave 201307250120-453759884-47047-17871-0 at slave(109)@140.211.11.27:47047 (hemera.apache.org)
I0725 01:20:50.382709 18241 master.cpp:1941] Adding slave 201307250120-453759884-47047-17871-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]
I0725 01:20:50.383337 18227 slave.cpp:638] Re-registered with master master@140.211.11.27:47047
I0725 01:20:50.383344 18241 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
W0725 01:20:50.385187 18241 master.cpp:2033] Possibly orphaned task 0 of framework 201307250120-453759884-47047-17871-0000 running on slave 201307250120-453759884-47047-17871-0 (hemera.apache.org)
I0725 01:20:50.386327 18227 hierarchical_allocator_process.hpp:430] Added slave 201307250120-453759884-47047-17871-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] available)
I0725 01:20:50.387579 18227 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307250120-453759884-47047-17871-0 in 11.448us
I0725 01:20:51.306485 18236 master.cpp:675] Re-registering framework 201307250120-453759884-47047-17871-0000 at scheduler(100)@140.211.11.27:47047
I0725 01:20:51.307410 18243 sched.cpp:268] Framework re-registered with 201307250120-453759884-47047-17871-0000
W0725 01:20:51.307569 18236 slave.cpp:1301] Ignoring updating pid for framework 201307250120-453759884-47047-17871-0000 because it is terminating
I0725 01:20:51.307975 18243 sched.cpp:282] Scheduler::reregistered took 24.279us
I0725 01:20:51.308110 18233 hierarchical_allocator_process.hpp:317] Added framework 201307250120-453759884-47047-17871-0000
I0725 01:20:51.311245 18233 hierarchical_allocator_process.hpp:709] Offering cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0 to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:51.311957 18233 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 772.221us
I0725 01:20:51.312059 18234 master.hpp:328] Adding offer 201307250120-453759884-47047-17871-0 with resources cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:51.313169 18234 master.cpp:1322] Sending 1 offers to framework 201307250120-453759884-47047-17871-0000
I0725 01:20:51.313855 18227 sched.cpp:340] Scheduler::resourceOffers took 32.849us
I0725 01:20:51.314520 17871 master.cpp:436] Master terminating
I0725 01:20:51.314540 18235 sched.cpp:498] Stopping framework '201307250120-453759884-47047-17871-0000'
I0725 01:20:51.315388 17871 master.cpp:209] Shutting down master
I0725 01:20:51.315466 18237 slave.cpp:493] Slave asked to shut down by master@140.211.11.27:47047
I0725 01:20:51.317430 17871 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201307250120-453759884-47047-17871-0
I0725 01:20:51.317529 18222 hierarchical_allocator_process.hpp:393] Deactivated framework 201307250120-453759884-47047-17871-0000
I0725 01:20:51.317854 18237 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by master@140.211.11.27:47047
I0725 01:20:51.318400 17871 master.hpp:338] Removing offer 201307250120-453759884-47047-17871-0 with resources cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] on slave 201307250120-453759884-47047-17871-0
I0725 01:20:51.325626 18222 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201307250120-453759884-47047-17871-0 from framework 201307250120-453759884-47047-17871-0000
W0725 01:20:51.326112 18237 slave.cpp:1161] Ignoring shutdown framework 201307250120-453759884-47047-17871-0000 because it is terminating
I0725 01:20:51.327476 18222 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):524; disk(*):56269; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):56269; ports(*):[31000-32000]) on slave 201307250120-453759884-47047-17871-0 from framework 201307250120-453759884-47047-17871-0000
I0725 01:20:51.327878 18237 slave.cpp:1912] master@140.211.11.27:47047 exited
I0725 01:20:51.328027 18222 hierarchical_allocator_process.hpp:348] Removed framework 201307250120-453759884-47047-17871-0000
W0725 01:20:51.328508 18237 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected
I0725 01:20:51.329077 18222 hierarchical_allocator_process.hpp:456] Removed slave 201307250120-453759884-47047-17871-0
I0725 01:20:51.329588 18241 detector.cpp:420] Master detector (slave(109)@140.211.11.27:47047) found 0 registered masters
I0725 01:20:51.334843 18241 detector.cpp:441] Master detector (slave(109)@140.211.11.27:47047) couldn't find any masters
I0725 01:20:51.333587 18237 slave.cpp:448] Slave terminating
I0725 01:20:51.336243 18237 slave.cpp:1140] Asked to shut down framework 201307250120-453759884-47047-17871-0000 by @0.0.0.0:0
W0725 01:20:51.336876 18237 slave.cpp:1161] Ignoring shutdown framework 201307250120-453759884-47047-17871-0000 because it is terminating
[ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (2057 ms)
I0725 01:20:51.340986 17871 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 42240
[----------] 2 tests from AllocatorZooKeeperTest/0 (4211 ms total)
[----------] Global test environment tear-down
[==========] 208 tests from 38 test cases ran. (71346 ms total)
[ PASSED ] 207 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] GroupTest.MultipleGroups
1 FAILED TEST
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/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 #1238
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1238/changes>