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/03/18 19:47:11 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #321
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/321/>
------------------------------------------
[...truncated 14596 lines...]
I0318 18:46:58.797374 11700 detector.cpp:485] Master detector (scheduler(69)@67.195.138.60:54985) found 1 registered masters
I0318 18:46:58.797859 11702 detector.cpp:485] Master detector (master@67.195.138.60:54985) found 1 registered masters
I0318 18:46:58.797916 11699 detector.cpp:531] Master detector (slave(78)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:46:58.798146 11700 detector.cpp:531] Master detector (scheduler(69)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:46:58.798784 11697 slave.cpp:452] New master detected at master@67.195.138.60:54985
I0318 18:46:58.799825 11699 status_update_manager.cpp:131] New master detected at master@67.195.138.60:54985
I0318 18:46:58.799232 11700 sched.cpp:182] New master at master@67.195.138.60:54985
W0318 18:46:58.801522 11704 master.cpp:666] Ignoring re-register framework message since not elected yet
I0318 18:46:58.799007 11702 detector.cpp:531] Master detector (master@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:46:58.802680 11702 master.cpp:601] Elected as master!
I0318 18:46:59.290760 11700 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.315630 11700 hierarchical_allocator_process.hpp:754] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0 for framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.316195 11700 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 25.48ms
I0318 18:46:59.659272 11699 master.cpp:679] Re-registering framework 201303181846-1015726915-54985-11530-0000 at scheduler(69)@67.195.138.60:54985
I0318 18:46:59.669550 11701 sched.cpp:217] Framework registered with 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.669658 11699 hierarchical_allocator_process.hpp:266] Added framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.670145 11699 hierarchical_allocator_process.hpp:666] No resources available to allocate!
I0318 18:46:59.670601 11699 hierarchical_allocator_process.hpp:597] Performed allocation for 0 slaves in 461.06us
I0318 18:46:59.772244 11704 hierarchical_allocator_process.hpp:666] No resources available to allocate!
I0318 18:46:59.772312 11704 hierarchical_allocator_process.hpp:597] Performed allocation for 0 slaves in 77.50us
I0318 18:46:59.800423 11702 master.cpp:1027] Attempting to re-register slave 201303181846-1015726915-54985-11530-0 at slave(78)@67.195.138.60:54985 (janus.apache.org)
I0318 18:46:59.800472 11702 master.cpp:1222] Master now considering a slave at janus.apache.org:54985 as active
I0318 18:46:59.800840 11702 master.cpp:1860] Adding slave 201303181846-1015726915-54985-11530-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 18:46:59.801327 11701 slave.cpp:531] Re-registered with master
I0318 18:46:59.801442 11702 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.802840 11702 slave.cpp:957] Updating framework 201303181846-1015726915-54985-11530-0000 pid to scheduler(69)@67.195.138.60:54985
I0318 18:46:59.802945 11699 hierarchical_allocator_process.hpp:393] Added slave 201303181846-1015726915-54985-11530-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0318 18:46:59.803606 11699 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.804080 11699 hierarchical_allocator_process.hpp:684] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0 to framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.804669 11699 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181846-1015726915-54985-11530-0 in 1.07ms
I0318 18:46:59.804720 11701 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.805515 11701 master.cpp:1325] Sending 1 offers to framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.806031 11703 sched.cpp:282] Received 1 offers
I0318 18:46:59.806675 11701 sched.cpp:422] Stopping framework '201303181846-1015726915-54985-11530-0000'
I0318 18:46:59.806956 11700 master.cpp:772] Asked to unregister framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.807459 11700 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.808064 11700 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.807517 11697 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.807497 11701 slave.cpp:890] Asked to shut down framework 201303181846-1015726915-54985-11530-0000 by master@67.195.138.60:54985
I0318 18:46:59.829473 11701 slave.cpp:895] Shutting down framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.829959 11701 slave.cpp:1631] Shutting down executor 'default' of framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.830467 11698 exec.cpp:321] Executor asked to shutdown
I0318 18:46:59.830492 11701 status_update_manager.cpp:232] Closing status update streams for framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.831389 11701 status_update_manager.cpp:433] Cleaning up status update stream for task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.831032 11703 slave.cpp:385] Slave terminating
I0318 18:46:59.832342 11703 slave.cpp:890] Asked to shut down framework 201303181846-1015726915-54985-11530-0000 by @0.0.0.0:0
I0318 18:46:59.832834 11703 slave.cpp:895] Shutting down framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.833336 11703 slave.cpp:1631] Shutting down executor 'default' of framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.833875 11702 status_update_manager.cpp:232] Closing status update streams for framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.829120 11697 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.833919 11704 master.cpp:535] Slave 201303181846-1015726915-54985-11530-0(janus.apache.org) disconnected
I0318 18:46:59.835685 11704 master.cpp:540] Removing disconnected slave 201303181846-1015726915-54985-11530-0(janus.apache.org) because it is not checkpointing!
I0318 18:46:59.834943 11697 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.854199 11703 master.cpp:475] Master terminating
I0318 18:46:59.854516 11697 hierarchical_allocator_process.hpp:310] Removed framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.855267 11530 master.cpp:281] Shutting down master
I0318 18:46:59.856470 11530 master.cpp:281] Shutting down master
I0318 18:46:59.882030 11703 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.882051 11530 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.883021 11702 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.883430 11702 hierarchical_allocator_process.hpp:310] Removed framework 201303181846-1015726915-54985-11530-0000
I0318 18:46:59.883877 11702 hierarchical_allocator_process.hpp:421] Removed slave 201303181846-1015726915-54985-11530-0
I0318 18:46:59.884455 11530 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 55768
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1609 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregisterFirst
I0318 18:46:59.886018 11530 jvm.cpp:34] looking up method <init>(Ljava/lang/String;)V
I0318 18:46:59.886087 11530 jvm.cpp:34] looking up method <init>(I)V
I0318 18:46:59.886427 11530 jvm.cpp:34] looking up method <init>(Ljava/net/InetSocketAddress;)V
I0318 18:46:59.886893 11530 jvm.cpp:34] looking up method startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
I0318 18:46:59.887363 11530 jvm.cpp:34] looking up method isAlive()Z
I0318 18:46:59.887835 11530 jvm.cpp:34] looking up method shutdown()V
I0318 18:46:59.890352 11530 jvm.cpp:34] looking up method <init>(Ljava/io/File;Ljava/io/File;)V
I0318 18:46:59.890836 11530 jvm.cpp:34] looking up method <init>()V
I0318 18:46:59.891171 11530 jvm.cpp:34] looking up method <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
I0318 18:46:59.891676 11530 jvm.cpp:34] looking up method getClientPort()I
I0318 18:46:59.892071 11530 jvm.cpp:34] looking up method closeSession(J)V
I0318 18:46:59.894230 11530 zookeeper_test_server.cpp:212] Started ZooKeeperTestServer on port 60470
I0318 18:46:59.894660 11699 master.cpp:307] Master started on 67.195.138.60:54985
I0318 18:46:59.894729 11699 master.cpp:322] Master ID: 201303181846-1015726915-54985-11530
W0318 18:46:59.895369 11697 master.cpp:79] No whitelist given. Advertising offers for all slaves
I0318 18:46:59.895443 11698 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:54985
I0318 18:46:59.895570 11703 slave.cpp:202] Slave started on 79)@67.195.138.60:54985
I0318 18:46:59.897029 11703 slave.cpp:203] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 18:46:59.898145 11698 slave.cpp:376] Finished recovery
I0318 18:46:59.964457 11699 detector.cpp:283] Master detector (master@67.195.138.60:54985) connected to ZooKeeper ...
I0318 18:46:59.964496 11699 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 18:46:59.976773 11697 detector.cpp:283] Master detector (slave(79)@67.195.138.60:54985) connected to ZooKeeper ...
I0318 18:46:59.976824 11697 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 18:46:59.977217 11698 detector.cpp:283] Master detector (scheduler(70)@67.195.138.60:54985) connected to ZooKeeper ...
I0318 18:46:59.977629 11698 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 18:47:00.002955 11699 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000000'
I0318 18:47:00.003231 11697 detector.cpp:485] Master detector (slave(79)@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.003404 11698 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.003581 11699 detector.cpp:485] Master detector (master@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.003963 11697 detector.cpp:531] Master detector (slave(79)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.005301 11699 detector.cpp:531] Master detector (master@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.005354 11702 slave.cpp:452] New master detected at master@67.195.138.60:54985
I0318 18:47:00.006316 11701 status_update_manager.cpp:131] New master detected at master@67.195.138.60:54985
I0318 18:47:00.005765 11699 master.cpp:601] Elected as master!
I0318 18:47:00.007138 11699 master.cpp:966] Attempting to register slave on janus.apache.org at slave(79)@67.195.138.60:54985
I0318 18:47:00.007573 11699 master.cpp:1222] Master now considering a slave at janus.apache.org:54985 as active
I0318 18:47:00.008047 11699 master.cpp:1860] Adding slave 201303181846-1015726915-54985-11530-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 18:47:00.008620 11703 slave.cpp:486] Registered with master; given slave ID 201303181846-1015726915-54985-11530-0
I0318 18:47:00.004436 11698 detector.cpp:531] Master detector (scheduler(70)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.008680 11702 hierarchical_allocator_process.hpp:393] Added slave 201303181846-1015726915-54985-11530-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0318 18:47:00.009963 11702 hierarchical_allocator_process.hpp:630] No users to allocate resources!
I0318 18:47:00.010413 11702 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181846-1015726915-54985-11530-0 in 451.55us
I0318 18:47:00.009541 11698 sched.cpp:182] New master at master@67.195.138.60:54985
I0318 18:47:00.011529 11699 master.cpp:644] Registering framework 201303181846-1015726915-54985-11530-0000 at scheduler(70)@67.195.138.60:54985
I0318 18:47:00.011895 11698 sched.cpp:217] Framework registered with 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.011934 11701 hierarchical_allocator_process.hpp:266] Added framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.013610 11701 hierarchical_allocator_process.hpp:658] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.014005 11701 hierarchical_allocator_process.hpp:684] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0 to framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.014595 11701 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 1.01ms
I0318 18:47:00.014646 11699 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.016337 11699 master.cpp:1325] Sending 1 offers to framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.016856 11700 sched.cpp:282] Received 1 offers
I0318 18:47:00.017524 11699 master.cpp:1532] Processing reply for offer 201303181846-1015726915-54985-11530-0 on slave 201303181846-1015726915-54985-11530-0 (janus.apache.org) for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.017838 11699 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.018247 11699 master.cpp:1649] Launching task 0 of framework 201303181846-1015726915-54985-11530-0000 with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0 (janus.apache.org)
I0318 18:47:00.018781 11700 slave.cpp:598] Got assigned task 0 for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.018853 11704 hierarchical_allocator_process.hpp:469] Framework 201303181846-1015726915-54985-11530-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=1024 unused on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.018856 11699 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.019829 11704 hierarchical_allocator_process.hpp:495] Framework 201303181846-1015726915-54985-11530-0000 filtered slave 201303181846-1015726915-54985-11530-0 for 5.00secs
I0318 18:47:00.021067 11700 paths.hpp:302] Created executor directory '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_0dVlcF/slaves/201303181846-1015726915-54985-11530-0/frameworks/201303181846-1015726915-54985-11530-0000/executors/default/runs/f85fb403-e61d-4048-86ea-fac98591d0a6'
I0318 18:47:00.021507 11698 slave.cpp:435] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregisterFirst_0dVlcF/slaves/201303181846-1015726915-54985-11530-0/frameworks/201303181846-1015726915-54985-11530-0000/executors/default/runs/f85fb403-e61d-4048-86ea-fac98591d0a6'
I0318 18:47:00.021510 11704 exec.cpp:170] Executor started at: executor(27)@67.195.138.60:54985 with pid 11530
I0318 18:47:00.022137 11704 slave.cpp:1021] Got registration for executor 'default' of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.022639 11704 slave.cpp:1096] Flushing queued tasks for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.022686 11701 exec.cpp:194] Executor registered on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.023959 11701 exec.cpp:258] Executor asked to run task '0'
I0318 18:47:00.024402 11701 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0318 18:47:00.026362 11701 slave.cpp:1204] Handling status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.026396 11701 slave.cpp:1251] Forwarding status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000 to the status update manager
I0318 18:47:00.027539 11699 status_update_manager.cpp:253] Received status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.027860 11699 status_update_manager.cpp:402] Creating StatusUpdate stream for task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.028343 11699 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.028841 11699 status_update_manager.cpp:288] Forwarding status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000 to the master at master@67.195.138.60:54985
I0318 18:47:00.029436 11701 master.cpp:1084] Status update from (941)@67.195.138.60:54985: task 0 of framework 201303181846-1015726915-54985-11530-0000 is now in state TASK_RUNNING
I0318 18:47:00.029487 11704 slave.cpp:1313] Sending ACK for status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000 to executor executor(27)@67.195.138.60:54985
I0318 18:47:00.029986 11700 sched.cpp:327] Status update: task 0 of framework 201303181846-1015726915-54985-11530-0000 is now in state TASK_RUNNING
I0318 18:47:00.030464 11698 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.030982 11703 master.cpp:475] Master terminating
I0318 18:47:00.031025 11704 slave.cpp:980] Got acknowledgement of status update for task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.032807 11704 slave.cpp:1363] Process exited: @0.0.0.0:0
I0318 18:47:00.032858 11698 status_update_manager.cpp:313] Received status update acknowledgement for task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.032950 11701 master.cpp:307] Master started on 67.195.138.60:54985
W0318 18:47:00.033110 11704 slave.cpp:1366] WARNING! Master disconnected! Waiting for a new master to be elected.
I0318 18:47:00.048651 11698 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.049103 11701 master.cpp:322] Master ID: 201303181847-1015726915-54985-11530
I0318 18:47:00.057091 11702 detector.cpp:283] Master detector (master@67.195.138.60:54985) connected to ZooKeeper ...
I0318 18:47:00.059962 11702 detector.cpp:300] Trying to create path '/znode' in ZooKeeper
I0318 18:47:00.057507 11703 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:54985) found 0 registered masters
W0318 18:47:00.059726 11700 master.cpp:79] No whitelist given. Advertising offers for all slaves
I0318 18:47:00.059825 11704 hierarchical_allocator_process.hpp:234] Initializing hierarchical allocator process with master : master@67.195.138.60:54985
I0318 18:47:00.057320 11697 detector.cpp:485] Master detector (slave(79)@67.195.138.60:54985) found 0 registered masters
I0318 18:47:00.082391 11697 detector.cpp:506] Master detector (slave(79)@67.195.138.60:54985) couldn't find any masters
I0318 18:47:00.060899 11703 detector.cpp:506] Master detector (scheduler(70)@67.195.138.60:54985) couldn't find any masters
I0318 18:47:00.082968 11698 slave.cpp:478] Lost master(s) ... waiting
I0318 18:47:00.090368 11699 sched.cpp:193] No master detected, waiting for another master
I0318 18:47:00.090417 11702 detector.cpp:330] Created ephemeral/sequence znode at '/znode/0000000002'
I0318 18:47:00.090718 11697 detector.cpp:485] Master detector (slave(79)@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.090941 11703 detector.cpp:485] Master detector (scheduler(70)@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.092996 11702 detector.cpp:485] Master detector (master@67.195.138.60:54985) found 1 registered masters
I0318 18:47:00.093503 11697 detector.cpp:531] Master detector (slave(79)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.094377 11697 slave.cpp:452] New master detected at master@67.195.138.60:54985
I0318 18:47:00.094398 11702 detector.cpp:531] Master detector (master@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.094079 11703 detector.cpp:531] Master detector (scheduler(70)@67.195.138.60:54985) got new master pid: master@67.195.138.60:54985
I0318 18:47:00.095820 11703 sched.cpp:182] New master at master@67.195.138.60:54985
W0318 18:47:00.094986 11697 master.cpp:994] Ignoring re-register slave message from janus.apache.org since not elected yet
I0318 18:47:00.094892 11698 status_update_manager.cpp:131] New master detected at master@67.195.138.60:54985
I0318 18:47:00.096842 11697 master.cpp:601] Elected as master!
I0318 18:47:00.897171 11700 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:00.897279 11700 hierarchical_allocator_process.hpp:754] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0 for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:00.897693 11700 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 538.44us
I0318 18:47:01.007313 11701 master.cpp:1027] Attempting to re-register slave 201303181846-1015726915-54985-11530-0 at slave(79)@67.195.138.60:54985 (janus.apache.org)
I0318 18:47:01.007357 11701 master.cpp:1222] Master now considering a slave at janus.apache.org:54985 as active
I0318 18:47:01.007740 11701 master.cpp:1860] Adding slave 201303181846-1015726915-54985-11530-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0318 18:47:01.008287 11701 master.hpp:289] Adding task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.008296 11704 slave.cpp:531] Re-registered with master
W0318 18:47:01.008610 11701 master.cpp:1946] Possibly orphaned task 0 of framework 201303181846-1015726915-54985-11530-0000 running on slave 201303181846-1015726915-54985-11530-0 (janus.apache.org)
I0318 18:47:01.009843 11699 hierarchical_allocator_process.hpp:393] Added slave 201303181846-1015726915-54985-11530-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=512; ports=[31000-32000]; disk=1024 available)
I0318 18:47:01.010530 11699 hierarchical_allocator_process.hpp:630] No users to allocate resources!
I0318 18:47:01.011473 11699 hierarchical_allocator_process.hpp:617] Performed allocation for slave 201303181846-1015726915-54985-11530-0 in 947.36us
I0318 18:47:01.012306 11701 master.cpp:679] Re-registering framework 201303181846-1015726915-54985-11530-0000 at scheduler(70)@67.195.138.60:54985
I0318 18:47:01.012518 11703 sched.cpp:217] Framework registered with 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.012557 11701 slave.cpp:957] Updating framework 201303181846-1015726915-54985-11530-0000 pid to scheduler(70)@67.195.138.60:54985
I0318 18:47:01.012650 11699 hierarchical_allocator_process.hpp:266] Added framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.013854 11699 hierarchical_allocator_process.hpp:658] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.014359 11699 hierarchical_allocator_process.hpp:684] Offering cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0 to framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.014929 11699 hierarchical_allocator_process.hpp:597] Performed allocation for 1 slaves in 1.09ms
I0318 18:47:01.015004 11702 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.015844 11702 master.cpp:1325] Sending 1 offers to framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.016346 11698 sched.cpp:282] Received 1 offers
I0318 18:47:01.016978 11702 sched.cpp:422] Stopping framework '201303181846-1015726915-54985-11530-0000'
I0318 18:47:01.018368 11700 master.cpp:772] Asked to unregister framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.018957 11704 slave.cpp:890] Asked to shut down framework 201303181846-1015726915-54985-11530-0000 by master@67.195.138.60:54985
I0318 18:47:01.019383 11704 slave.cpp:895] Shutting down framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.018990 11698 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.018959 11700 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.019844 11704 slave.cpp:1631] Shutting down executor 'default' of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.040794 11704 exec.cpp:321] Executor asked to shutdown
I0318 18:47:01.040441 11702 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=1; mem=512; ports=[]; disk=0) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.041786 11702 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.040812 11698 status_update_manager.cpp:232] Closing status update streams for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.041312 11703 slave.cpp:385] Slave terminating
I0318 18:47:01.043123 11703 slave.cpp:890] Asked to shut down framework 201303181846-1015726915-54985-11530-0000 by @0.0.0.0:0
I0318 18:47:01.043591 11703 slave.cpp:895] Shutting down framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.044060 11703 slave.cpp:1631] Shutting down executor 'default' of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.042659 11698 status_update_manager.cpp:433] Cleaning up status update stream for task 0 of framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.040385 11700 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=1024 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.045060 11698 status_update_manager.cpp:232] Closing status update streams for framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.045588 11700 master.cpp:535] Slave 201303181846-1015726915-54985-11530-0(janus.apache.org) disconnected
I0318 18:47:01.045735 11699 hierarchical_allocator_process.hpp:310] Removed framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.048612 11700 master.cpp:540] Removing disconnected slave 201303181846-1015726915-54985-11530-0(janus.apache.org) because it is not checkpointing!
I0318 18:47:01.050372 11702 master.cpp:475] Master terminating
I0318 18:47:01.051267 11530 master.cpp:281] Shutting down master
I0318 18:47:01.052041 11530 master.cpp:281] Shutting down master
I0318 18:47:01.052155 11530 master.hpp:300] Removing task with resources cpus=1; mem=512 on slave 201303181846-1015726915-54985-11530-0
I0318 18:47:01.052186 11701 hierarchical_allocator_process.hpp:357] Deactivated framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.053051 11701 hierarchical_allocator_process.hpp:542] Recovered cpus=1; mem=512 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201303181846-1015726915-54985-11530-0 from framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.064957 11701 hierarchical_allocator_process.hpp:310] Removed framework 201303181846-1015726915-54985-11530-0000
I0318 18:47:01.065119 11530 zookeeper_test_server.cpp:195] Shutdown ZooKeeperTestServer on port 60470
I0318 18:47:01.065350 11701 hierarchical_allocator_process.hpp:421] Removed slave 201303181846-1015726915-54985-11530-0
[ OK ] AllocatorZooKeeperTest/0.SlaveReregisterFirst (1181 ms)
[----------] 2 tests from AllocatorZooKeeperTest/0 (2790 ms total)
[----------] Global test environment tear-down
[==========] 193 tests from 40 test cases ran. (137262 ms total)
[ PASSED ] 192 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] CoordinatorTest.TruncateNotLearnedFill
1 FAILED TEST
YOU HAVE 1 DISABLED TEST
FAIL: mesos-tests
==================
1 of 1 test failed
==================
make[3]: *** [check-TESTS] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/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-Out-Of-Src-Set-JAVA_HOME #322
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/322/changes>