Looks like the coordinator tests are flaky in general, given: https://issues.apache.org/jira/browse/MESOS-403 https://issues.apache.org/jira/browse/MESOS-402 https://issues.apache.org/jira/browse/MESOS-301
On Thu, Apr 18, 2013 at 12:09 AM, Apache Jenkins Server < [email protected]> wrote: > See < > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/344/changes > > > > Changes: > > [vinodkone] Fixed a bug in the status update manager to properly handle > duplicate > ACK of an old update when waiting for the ACK of a new update. > > Review: https://reviews.apache.org/r/10596 > > ------------------------------------------ > [...truncated 17679 lines...] > I0418 07:09:37.115166 9541 slave.cpp:1350] Status update manager > successfully handled status update acknowledgement for task 0 of framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.117921 9541 slave.cpp:564] Lost master(s) ... waiting > I0418 07:09:37.116241 9543 detector.cpp:506] Master detector > (scheduler(79)@67.195.138.60:58934) couldn't find any masters > I0418 07:09:37.119007 9543 sched.cpp:193] No master detected, waiting for > another master > I0418 07:09:37.115710 9548 detector.cpp:300] Trying to create path > '/znode' in ZooKeeper > I0418 07:09:37.079193 9544 master.cpp:324] Master ID: > 201304180709-1015726915-58934-9521 > W0418 07:09:37.120658 9545 master.cpp:81] No whitelist given. Advertising > offers for all slaves > I0418 07:09:37.120800 9543 hierarchical_allocator_process.hpp:236] > Initializing hierarchical allocator process with master : > [email protected]:58934 > I0418 07:09:37.192168 9548 detector.cpp:330] Created ephemeral/sequence > znode at '/znode/0000000002' > I0418 07:09:37.192690 9541 detector.cpp:485] Master detector > (scheduler(79)@67.195.138.60:58934) found 1 registered masters > I0418 07:09:37.193433 9548 detector.cpp:485] Master detector ( > [email protected]:58934) found 1 registered masters > I0418 07:09:37.192826 9546 detector.cpp:485] Master detector (slave(89)@ > 67.195.138.60:58934) found 1 registered masters > I0418 07:09:37.194188 9541 detector.cpp:531] Master detector > (scheduler(79)@67.195.138.60:58934) got new master pid: > [email protected]:58934 > I0418 07:09:37.194469 9548 detector.cpp:531] Master detector ( > [email protected]:58934) got new master pid: > [email protected]:58934 > I0418 07:09:37.195399 9548 master.cpp:603] Elected as master! > I0418 07:09:37.194965 9541 sched.cpp:182] New master at > [email protected]:58934 > I0418 07:09:37.194919 9546 detector.cpp:531] Master detector (slave(89)@ > 67.195.138.60:58934) got new master pid: [email protected]:58934 > I0418 07:09:37.207095 9543 master.cpp:681] Re-registering framework > 201304180709-1015726915-58934-9521-0000 at scheduler(79)@ > 67.195.138.60:58934 > I0418 07:09:37.215191 9546 slave.cpp:530] New master detected at > [email protected]:58934 > I0418 07:09:37.216685 9541 sched.cpp:217] Framework registered with > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.216768 9542 hierarchical_allocator_process.hpp:268] Added > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.235431 9542 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0418 07:09:37.236078 9542 hierarchical_allocator_process.hpp:599] > Performed allocation for 0 slaves in 649.241000000000099us > I0418 07:09:37.234381 9544 status_update_manager.cpp:154] New master > detected at [email protected]:58934 > I0418 07:09:37.234524 9548 master.cpp:1029] Attempting to re-register > slave 201304180709-1015726915-58934-9521-0 at slave(89)@ > 67.195.138.60:58934 (janus.apache.org) > I0418 07:09:37.249815 9548 master.cpp:1224] Master now considering a > slave at janus.apache.org:58934 as active > I0418 07:09:37.271070 9548 master.cpp:1862] Adding slave > 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0418 07:09:37.281239 9546 slave.cpp:626] Re-registered with master > [email protected]:58934 > I0418 07:09:37.281314 9548 master.hpp:289] Adding task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.321563 9548 slave.cpp:1279] Updating framework > 201304180709-1015726915-58934-9521-0000 pid to scheduler(79)@ > 67.195.138.60:58934 > I0418 07:09:37.321640 9546 hierarchical_allocator_process.hpp:395] Added > slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) with > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; > ports=[31000-32000]; disk=1024 available) > I0418 07:09:37.346547 9546 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on > slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.365967 9546 hierarchical_allocator_process.hpp:686] > Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.393231 9546 hierarchical_allocator_process.hpp:619] > Performed allocation for slave 201304180709-1015726915-58934-9521-0 in > 46.696567999999999ms > I0418 07:09:37.393301 9543 master.hpp:309] Adding offer with resources > cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.425329 9543 master.cpp:1327] Sending 1 offers to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.441244 9543 sched.cpp:282] Received 1 offers > I0418 07:09:37.442127 9545 sched.cpp:422] Stopping framework > '201304180709-1015726915-58934-9521-0000' > I0418 07:09:37.442687 9544 master.cpp:774] Asked to unregister framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.443249 9548 hierarchical_allocator_process.hpp:359] > Deactivated framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.443289 9543 slave.cpp:1117] Asked to shut down framework > 201304180709-1015726915-58934-9521-0000 by [email protected]:58934 > I0418 07:09:37.443289 9544 master.hpp:300] Removing task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.444752 9543 slave.cpp:1142] Shutting down framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.511596 9543 slave.cpp:2305] Shutting down executor > 'default' of framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.499204 9542 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; > disk=0) on slave 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.499203 9544 master.hpp:318] Removing offer with resources > cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.519052 9541 exec.cpp:321] Executor asked to shutdown > I0418 07:09:37.532840 9542 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total > allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave > 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.542104 9521 slave.cpp:441] Slave terminating > I0418 07:09:37.543591 9521 slave.cpp:1117] Asked to shut down framework > 201304180709-1015726915-58934-9521-0000 by @0.0.0.0:0 > W0418 07:09:37.544149 9521 slave.cpp:1138] Ignoring shutdown framework > 201304180709-1015726915-58934-9521-0000 because it is terminating > I0418 07:09:37.544931 9547 master.cpp:537] Slave > 201304180709-1015726915-58934-9521-0(janus.apache.org) disconnected > I0418 07:09:37.545574 9547 master.cpp:542] Removing disconnected slave > 201304180709-1015726915-58934-9521-0(janus.apache.org) because it is not > checkpointing! > I0418 07:09:37.595298 9521 master.cpp:477] Master terminating > I0418 07:09:37.596256 9521 master.cpp:283] Shutting down master > I0418 07:09:37.597055 9521 master.cpp:283] Shutting down master > I0418 07:09:37.625113 9546 hierarchical_allocator_process.hpp:359] > Deactivated framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.625130 9521 master.hpp:300] Removing task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.626106 9542 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; > ports=[31000-32000]; disk=1024) on slave > 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.626560 9542 hierarchical_allocator_process.hpp:312] > Removed framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.626981 9542 hierarchical_allocator_process.hpp:423] > Removed slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.627467 9521 zookeeper_test_server.cpp:195] Shutdown > ZooKeeperTestServer on port 42054 > [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1994 ms) > [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst > I0418 07:09:37.629029 9521 jvm.cpp:34] looking up method > <init>(Ljava/lang/String;)V > I0418 07:09:37.629067 9521 jvm.cpp:34] looking up method <init>(I)V > I0418 07:09:37.629406 9521 jvm.cpp:34] looking up method > <init>(Ljava/net/InetSocketAddress;)V > I0418 07:09:37.629878 9521 jvm.cpp:34] looking up method > startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V > I0418 07:09:37.630349 9521 jvm.cpp:34] looking up method isAlive()Z > I0418 07:09:37.630828 9521 jvm.cpp:34] looking up method shutdown()V > I0418 07:09:37.633327 9521 jvm.cpp:34] looking up method > <init>(Ljava/io/File;Ljava/io/File;)V > I0418 07:09:37.633762 9521 jvm.cpp:34] looking up method <init>()V > I0418 07:09:37.634490 9521 jvm.cpp:34] looking up method > <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V > I0418 07:09:37.635057 9521 jvm.cpp:34] looking up method getClientPort()I > I0418 07:09:37.635445 9521 jvm.cpp:34] looking up method closeSession(J)V > I0418 07:09:37.638254 9521 zookeeper_test_server.cpp:212] Started > ZooKeeperTestServer on port 46008 > I0418 07:09:37.638540 9542 master.cpp:309] Master started on > 67.195.138.60:58934 > I0418 07:09:37.638659 9542 master.cpp:324] Master ID: > 201304180709-1015726915-58934-9521 > W0418 07:09:37.639525 9548 master.cpp:81] No whitelist given. Advertising > offers for all slaves > I0418 07:09:37.639544 9547 slave.cpp:210] Slave started on 90)@ > 67.195.138.60:58934 > I0418 07:09:37.640091 9547 slave.cpp:211] Slave resources: cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0418 07:09:37.639588 9541 hierarchical_allocator_process.hpp:236] > Initializing hierarchical allocator process with master : > [email protected]:58934 > I0418 07:09:37.641444 9543 slave.cpp:391] Finished recovery > I0418 07:09:37.730254 9543 detector.cpp:283] Master detector ( > [email protected]:58934) connected to ZooKeeper ... > I0418 07:09:37.730304 9543 detector.cpp:300] Trying to create path > '/znode' in ZooKeeper > I0418 07:09:37.783726 9542 detector.cpp:283] Master detector (slave(90)@ > 67.195.138.60:58934) connected to ZooKeeper ... > I0418 07:09:37.783792 9542 detector.cpp:300] Trying to create path > '/znode' in ZooKeeper > I0418 07:09:37.784126 9546 detector.cpp:283] Master detector > (scheduler(80)@67.195.138.60:58934) connected to ZooKeeper ... > I0418 07:09:37.848327 9546 detector.cpp:300] Trying to create path > '/znode' in ZooKeeper > I0418 07:09:37.847873 9543 detector.cpp:330] Created ephemeral/sequence > znode at '/znode/0000000000' > I0418 07:09:37.906265 9542 detector.cpp:485] Master detector (slave(90)@ > 67.195.138.60:58934) found 1 registered masters > I0418 07:09:37.906491 9546 detector.cpp:485] Master detector > (scheduler(80)@67.195.138.60:58934) found 1 registered masters > I0418 07:09:37.906929 9543 detector.cpp:485] Master detector ( > [email protected]:58934) found 1 registered masters > I0418 07:09:37.929257 9542 detector.cpp:531] Master detector (slave(90)@ > 67.195.138.60:58934) got new master pid: [email protected]:58934 > I0418 07:09:37.947679 9546 detector.cpp:531] Master detector > (scheduler(80)@67.195.138.60:58934) got new master pid: > [email protected]:58934 > I0418 07:09:37.975690 9543 detector.cpp:531] Master detector ( > [email protected]:58934) got new master pid: > [email protected]:58934 > I0418 07:09:37.975761 9547 slave.cpp:530] New master detected at > [email protected]:58934 > I0418 07:09:37.977236 9541 status_update_manager.cpp:154] New master > detected at [email protected]:58934 > I0418 07:09:37.976681 9543 master.cpp:603] Elected as master! > I0418 07:09:37.976222 9546 sched.cpp:182] New master at > [email protected]:58934 > I0418 07:09:37.978808 9543 master.cpp:968] Attempting to register slave > on janus.apache.org at slave(90)@67.195.138.60:58934 > I0418 07:09:37.979779 9543 master.cpp:1224] Master now considering a > slave at janus.apache.org:58934 as active > I0418 07:09:37.980288 9543 master.cpp:1862] Adding slave > 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0418 07:09:37.980798 9548 slave.cpp:590] Registered with master > [email protected]:58934; given slave ID > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.980926 9543 master.cpp:646] Registering framework > 201304180709-1015726915-58934-9521-0000 at scheduler(80)@ > 67.195.138.60:58934 > I0418 07:09:37.980921 9544 hierarchical_allocator_process.hpp:395] Added > slave 201304180709-1015726915-58934-9521-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) > I0418 07:09:37.982277 9544 hierarchical_allocator_process.hpp:632] No > users to allocate resources! > I0418 07:09:37.982770 9544 hierarchical_allocator_process.hpp:619] > Performed allocation for slave 201304180709-1015726915-58934-9521-0 in > 497.296000000000049us > I0418 07:09:37.983366 9544 hierarchical_allocator_process.hpp:268] Added > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.981854 9548 sched.cpp:217] Framework registered with > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.983981 9544 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on > slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.987763 9544 hierarchical_allocator_process.hpp:686] > Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.989272 9544 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 5.307488000000000ms > I0418 07:09:37.989312 9543 master.hpp:309] Adding offer with resources > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.990190 9543 master.cpp:1327] Sending 1 offers to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.990789 9547 sched.cpp:282] Received 1 offers > I0418 07:09:37.991457 9547 master.cpp:1534] Processing reply for offer > 201304180709-1015726915-58934-9521-0 on slave > 201304180709-1015726915-58934-9521-0 (janus.apache.org) for framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.991829 9547 master.hpp:289] Adding task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.992293 9547 master.cpp:1651] Launching task 0 of framework > 201304180709-1015726915-58934-9521-0000 with resources cpus=1; mem=500 on > slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) > I0418 07:09:37.992863 9542 slave.cpp:719] Got assigned task 0 for > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.993502 9542 slave.cpp:792] Launching task 0 for framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:37.992941 9547 master.hpp:318] Removing offer with resources > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.992923 9543 hierarchical_allocator_process.hpp:471] > Framework 201304180709-1015726915-58934-9521-0000 left cpus=1; mem=524; > ports=[31000-32000]; disk=1024 unused on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:37.995631 9542 paths.hpp:302] Created executor directory > '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Vm4Msw/slaves/201304180709-1015726915-58934-9521-0/frameworks/201304180709-1015726915-58934-9521-0000/executors/default/runs/7469c0ad-1be1-4c40-ab95-da20b56136e9' > I0418 07:09:38.029853 9543 hierarchical_allocator_process.hpp:497] > Framework 201304180709-1015726915-58934-9521-0000 filtered slave > 201304180709-1015726915-58934-9521-0 for 5.000000000000000secs > I0418 07:09:38.045490 9542 slave.cpp:940] Queuing task '0' for executor > default of framework '201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.045616 9545 exec.cpp:170] Executor started at: > executor(31)@67.195.138.60:58934 with pid 9521 > I0418 07:09:38.083941 9542 slave.cpp:512] Successfully attached file > '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_Vm4Msw/slaves/201304180709-1015726915-58934-9521-0/frameworks/201304180709-1015726915-58934-9521-0000/executors/default/runs/7469c0ad-1be1-4c40-ab95-da20b56136e9' > I0418 07:09:38.087597 9542 slave.cpp:1397] Got registration for executor > 'default' of framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.088451 9542 slave.cpp:1511] Flushing queued tasks for > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.088503 9548 exec.cpp:194] Executor registered on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:38.089443 9548 exec.cpp:258] Executor asked to run task '0' > I0418 07:09:38.089956 9548 exec.cpp:382] Executor sending status update > for task 0 in state TASK_RUNNING > I0418 07:09:38.091778 9548 slave.cpp:1739] Handling status update > TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.091879 9548 status_update_manager.cpp:289] Received status > update TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 with checkpoint=false > I0418 07:09:38.092195 9548 status_update_manager.cpp:450] Creating > StatusUpdate stream for task 0 of framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.093336 9548 status_update_manager.hpp:350] Handling UPDATE > for status update TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.094354 9548 status_update_manager.cpp:335] Forwarding > status update TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 to the master at > [email protected]:58934 > I0418 07:09:38.094879 9548 slave.cpp:1799] Sending ACK for status update > TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 to executor executor(31)@ > 67.195.138.60:58934 > I0418 07:09:38.094883 9547 master.cpp:1086] Status update from (821)@ > 67.195.138.60:58934: task 0 of framework > 201304180709-1015726915-58934-9521-0000 is now in state TASK_RUNNING > I0418 07:09:38.095398 9545 exec.cpp:289] Executor received ACK for status > update of task 0 of framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.095947 9548 sched.cpp:327] Received status update > TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 from slave(90)@67.195.138.60:58934 > I0418 07:09:38.097117 9548 sched.cpp:360] Sending ACK for status update > TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 to slave(90)@67.195.138.60:58934 > I0418 07:09:38.097295 9521 master.cpp:477] Master terminating > I0418 07:09:38.097573 9547 slave.cpp:1307] Got acknowledgement of status > update for task 0 of framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.098603 9547 slave.cpp:1874] [email protected]:58934exited > I0418 07:09:38.098675 9545 status_update_manager.cpp:360] Received status > update acknowledgement dbe97c67-45e7-49fd-97b1-da0420affb95 for task 0 of > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.099980 9545 status_update_manager.hpp:350] Handling ACK > for status update TASK_RUNNING from task 0 of framework > 201304180709-1015726915-58934-9521-0000 > W0418 07:09:38.099115 9547 slave.cpp:1877] Master disconnected! Waiting > for a new master to be elected > I0418 07:09:38.098877 9541 master.cpp:309] Master started on > 67.195.138.60:58934 > I0418 07:09:38.165457 9542 detector.cpp:283] Master detector ( > [email protected]:58934) connected to ZooKeeper ... > I0418 07:09:38.165819 9546 detector.cpp:485] Master detector (slave(90)@ > 67.195.138.60:58934) found 0 registered masters > I0418 07:09:38.165879 9548 detector.cpp:485] Master detector > (scheduler(80)@67.195.138.60:58934) found 0 registered masters > I0418 07:09:38.255322 9548 detector.cpp:506] Master detector > (scheduler(80)@67.195.138.60:58934) couldn't find any masters > I0418 07:09:38.203160 9541 master.cpp:324] Master ID: > 201304180709-1015726915-58934-9521 > I0418 07:09:38.222904 9542 detector.cpp:300] Trying to create path > '/znode' in ZooKeeper > I0418 07:09:38.254866 9546 detector.cpp:506] Master detector (slave(90)@ > 67.195.138.60:58934) couldn't find any masters > I0418 07:09:38.189137 9547 slave.cpp:1350] Status update manager > successfully handled status update acknowledgement for task 0 of framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.258441 9547 slave.cpp:564] Lost master(s) ... waiting > W0418 07:09:38.257006 9544 master.cpp:81] No whitelist given. Advertising > offers for all slaves > I0418 07:09:38.257127 9545 hierarchical_allocator_process.hpp:236] > Initializing hierarchical allocator process with master : > [email protected]:58934 > I0418 07:09:38.256419 9548 sched.cpp:193] No master detected, waiting for > another master > I0418 07:09:38.308862 9542 detector.cpp:330] Created ephemeral/sequence > znode at '/znode/0000000002' > I0418 07:09:38.309376 9541 detector.cpp:485] Master detector (slave(90)@ > 67.195.138.60:58934) found 1 registered masters > I0418 07:09:38.309671 9542 detector.cpp:485] Master detector ( > [email protected]:58934) found 1 registered masters > I0418 07:09:38.309871 9546 detector.cpp:485] Master detector > (scheduler(80)@67.195.138.60:58934) found 1 registered masters > I0418 07:09:38.309967 9541 detector.cpp:531] Master detector (slave(90)@ > 67.195.138.60:58934) got new master pid: [email protected]:58934 > I0418 07:09:38.310813 9546 detector.cpp:531] Master detector > (scheduler(80)@67.195.138.60:58934) got new master pid: > [email protected]:58934 > I0418 07:09:38.310852 9541 slave.cpp:530] New master detected at > [email protected]:58934 > I0418 07:09:38.310294 9542 detector.cpp:531] Master detector ( > [email protected]:58934) got new master pid: > [email protected]:58934 > I0418 07:09:38.323439 9546 sched.cpp:182] New master at > [email protected]:58934 > I0418 07:09:38.340859 9545 status_update_manager.cpp:154] New master > detected at [email protected]:58934 > W0418 07:09:38.340945 9547 master.cpp:996] Ignoring re-register slave > message from janus.apache.org since not elected yet > I0418 07:09:38.348153 9547 master.cpp:603] Elected as master! > I0418 07:09:38.645666 9543 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on > slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:38.682126 9543 hierarchical_allocator_process.hpp:756] > Filtered cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 for framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:38.699092 9543 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 53.462091999999998ms > I0418 07:09:38.978210 9541 master.cpp:1029] Attempting to re-register > slave 201304180709-1015726915-58934-9521-0 at slave(90)@ > 67.195.138.60:58934 (janus.apache.org) > I0418 07:09:39.023558 9541 master.cpp:1224] Master now considering a > slave at janus.apache.org:58934 as active > I0418 07:09:39.043400 9541 master.cpp:1862] Adding slave > 201304180709-1015726915-58934-9521-0 at janus.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0418 07:09:39.071180 9542 slave.cpp:626] Re-registered with master > [email protected]:58934 > I0418 07:09:39.071204 9541 master.hpp:289] Adding task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > W0418 07:09:39.072150 9541 master.cpp:1948] Possibly orphaned task 0 of > framework 201304180709-1015726915-58934-9521-0000 running on slave > 201304180709-1015726915-58934-9521-0 (janus.apache.org) > I0418 07:09:39.072944 9541 hierarchical_allocator_process.hpp:395] Added > slave 201304180709-1015726915-58934-9521-0 (janus.apache.org) with > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=1; mem=524; > ports=[31000-32000]; disk=1024 available) > I0418 07:09:39.073719 9541 hierarchical_allocator_process.hpp:632] No > users to allocate resources! > I0418 07:09:39.074254 9541 hierarchical_allocator_process.hpp:619] > Performed allocation for slave 201304180709-1015726915-58934-9521-0 in > 535.790000000000077us > I0418 07:09:39.293455 9543 hierarchical_allocator_process.hpp:632] No > users to allocate resources! > I0418 07:09:39.342628 9546 master.cpp:681] Re-registering framework > 201304180709-1015726915-58934-9521-0000 at scheduler(80)@ > 67.195.138.60:58934 > I0418 07:09:39.347746 9543 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 54.293908000000002ms > I0418 07:09:39.387366 9548 sched.cpp:217] Framework registered with > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.387408 9546 slave.cpp:1279] Updating framework > 201304180709-1015726915-58934-9521-0000 pid to scheduler(80)@ > 67.195.138.60:58934 > I0418 07:09:39.404597 9543 hierarchical_allocator_process.hpp:268] Added > framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.406267 9543 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=1; mem=524; ports=[31000-32000]; disk=1024 on > slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.406805 9543 hierarchical_allocator_process.hpp:686] > Offering cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.407462 9543 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 1.210720000000000ms > I0418 07:09:39.407532 9541 master.hpp:309] Adding offer with resources > cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.408617 9541 master.cpp:1327] Sending 1 offers to framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.415127 9541 sched.cpp:282] Received 1 offers > I0418 07:09:39.415869 9544 sched.cpp:422] Stopping framework > '201304180709-1015726915-58934-9521-0000' > I0418 07:09:39.416069 9545 master.cpp:774] Asked to unregister framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.416596 9547 slave.cpp:1117] Asked to shut down framework > 201304180709-1015726915-58934-9521-0000 by [email protected]:58934 > I0418 07:09:39.417279 9547 slave.cpp:1142] Shutting down framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.417779 9547 slave.cpp:2305] Shutting down executor > 'default' of framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.418282 9548 exec.cpp:321] Executor asked to shutdown > I0418 07:09:39.416609 9545 master.hpp:300] Removing task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.416606 9541 hierarchical_allocator_process.hpp:359] > Deactivated framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.442165 9541 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=500 (total allocatable: cpus=1; mem=500; ports=[]; > disk=0) on slave 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.442667 9541 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=524; ports=[31000-32000]; disk=1024 (total > allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave > 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.441767 9545 master.hpp:318] Removing offer with resources > cpus=1; mem=524; ports=[31000-32000]; disk=1024 on slave > 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.443838 9541 slave.cpp:441] Slave terminating > I0418 07:09:39.444017 9541 slave.cpp:1117] Asked to shut down framework > 201304180709-1015726915-58934-9521-0000 by @0.0.0.0:0 > W0418 07:09:39.444484 9541 slave.cpp:1138] Ignoring shutdown framework > 201304180709-1015726915-58934-9521-0000 because it is terminating > I0418 07:09:39.445107 9544 master.cpp:537] Slave > 201304180709-1015726915-58934-9521-0(janus.apache.org) disconnected > I0418 07:09:39.445895 9544 master.cpp:542] Removing disconnected slave > 201304180709-1015726915-58934-9521-0(janus.apache.org) because it is not > checkpointing! > I0418 07:09:39.446449 9521 master.cpp:477] Master terminating > I0418 07:09:39.467246 9521 master.cpp:283] Shutting down master > I0418 07:09:39.500448 9521 master.cpp:283] Shutting down master > I0418 07:09:39.500524 9521 master.hpp:300] Removing task with resources > cpus=1; mem=500 on slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.500572 9542 hierarchical_allocator_process.hpp:359] > Deactivated framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.501477 9542 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=500 (total allocatable: cpus=2; mem=1024; > ports=[31000-32000]; disk=1024) on slave > 201304180709-1015726915-58934-9521-0 from framework > 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.501881 9542 hierarchical_allocator_process.hpp:312] > Removed framework 201304180709-1015726915-58934-9521-0000 > I0418 07:09:39.502315 9542 hierarchical_allocator_process.hpp:423] > Removed slave 201304180709-1015726915-58934-9521-0 > I0418 07:09:39.514889 9521 zookeeper_test_server.cpp:195] Shutdown > ZooKeeperTestServer on port 46008 > [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1887 ms) > [----------] 2 tests from AllocatorZooKeeperTest/0 (3881 ms total) > > [----------] Global test environment tear-down > [==========] 195 tests from 39 test cases ran. (155719 ms total) > [ PASSED ] 192 tests. > [ FAILED ] 3 tests, listed below: > [ FAILED ] CoordinatorTest.Fill > [ FAILED ] CoordinatorTest.MultipleAppendsNotLearnedFill > [ FAILED ] CoordinatorTest.TruncateNotLearnedFill > > 3 FAILED TESTS > 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 > Build step 'Execute shell' marked build as failure >
