https://issues.apache.org/jira/browse/MESOS-413
On Wed, Mar 20, 2013 at 6:10 PM, Apache Jenkins Server < [email protected]> wrote: > See < > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/287/changes > > > > Changes: > > [vinodkone] Added registration timeout for executors. > > Review: https://reviews.apache.org/r/8077 > > [vinodkone] Added a more comprehensive slave recovery test to confirm that > the > restarted slave can communicate with scheduler, executor and master. > > Review: https://reviews.apache.org/r/9992 > > ------------------------------------------ > [...truncated 12609 lines...] > I0321 01:10:26.344229 31935 status_update_manager.cpp:131] New master > detected at [email protected]:52923 > I0321 01:10:26.346981 31936 master.cpp:968] Attempting to register slave > on janus.apache.org at slave(79)@67.195.138.60:52923 > I0321 01:10:26.347973 31936 master.cpp:1224] Master now considering a > slave at janus.apache.org:52923 as active > I0321 01:10:26.348474 31936 master.cpp:1862] Adding slave > 201303210110-1015726915-52923-31915-0 at janus.apache.org with cpus=3; > mem=1024; ports=[31000-32000]; disk=14049 > I0321 01:10:26.349043 31936 slave.cpp:487] Registered with master; given > slave ID 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.349172 31940 hierarchical_allocator_process.hpp:395] Added > slave 201303210110-1015726915-52923-31915-0 (janus.apache.org) with > cpus=3; mem=1024; ports=[31000-32000]; disk=14049 (and cpus=3; mem=1024; > ports=[31000-32000]; disk=14049 available) > I0321 01:10:26.349931 31940 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=14049 on > slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.350442 31940 hierarchical_allocator_process.hpp:686] > Offering cpus=3; mem=1024; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.351016 31940 hierarchical_allocator_process.hpp:619] > Performed allocation for slave 201303210110-1015726915-52923-31915-0 in > 1.09ms > I0321 01:10:26.351059 31936 master.hpp:309] Adding offer with resources > cpus=3; mem=1024; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.352747 31936 master.cpp:1327] Sending 1 offers to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.353261 31941 sched.cpp:282] Received 1 offers > I0321 01:10:26.353957 31936 master.cpp:1534] Processing reply for offer > 201303210110-1015726915-52923-31915-0 on slave > 201303210110-1015726915-52923-31915-0 (janus.apache.org) for framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.354317 31936 master.hpp:289] Adding task with resources > cpus=1; mem=256 on slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.354735 31936 master.cpp:1651] Launching task 0 of framework > 201303210110-1015726915-52923-31915-0000 with resources cpus=1; mem=256 on > slave 201303210110-1015726915-52923-31915-0 (janus.apache.org) > I0321 01:10:26.355278 31941 slave.cpp:599] Got assigned task 0 for > framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.356691 31941 paths.hpp:302] Created executor directory > '/tmp/AllocatorTest_0_TaskFinished_m8lWIA/slaves/201303210110-1015726915-52923-31915-0/frameworks/201303210110-1015726915-52923-31915-0000/executors/default/runs/3156fec3-00d6-4c84-963a-f34345a974dd' > I0321 01:10:26.355288 31936 master.hpp:289] Adding task with resources > cpus=1; mem=256 on slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.357118 31941 slave.cpp:436] Successfully attached file > '/tmp/AllocatorTest_0_TaskFinished_m8lWIA/slaves/201303210110-1015726915-52923-31915-0/frameworks/201303210110-1015726915-52923-31915-0000/executors/default/runs/3156fec3-00d6-4c84-963a-f34345a974dd' > I0321 01:10:26.357209 31939 exec.cpp:170] Executor started at: > executor(25)@67.195.138.60:52923 with pid 31915 > I0321 01:10:26.357311 31936 master.cpp:1651] Launching task 1 of framework > 201303210110-1015726915-52923-31915-0000 with resources cpus=1; mem=256 on > slave 201303210110-1015726915-52923-31915-0 (janus.apache.org) > I0321 01:10:26.358922 31939 slave.cpp:1033] Got registration for executor > 'default' of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.359464 31936 master.hpp:318] Removing offer with resources > cpus=3; mem=1024; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.359555 31940 hierarchical_allocator_process.hpp:471] > Framework 201303210110-1015726915-52923-31915-0000 left cpus=1; mem=512; > ports=[31000-32000]; disk=14049 unused on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.359930 31939 slave.cpp:1108] Flushing queued tasks for > framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.361453 31939 slave.cpp:599] Got assigned task 1 for > framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.362232 31939 slave.cpp:737] Sending task '1' to executor > 'default' of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.359987 31935 exec.cpp:194] Executor registered on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:26.364218 31935 exec.cpp:258] Executor asked to run task '0' > I0321 01:10:26.364758 31935 exec.cpp:258] Executor asked to run task '1' > I0321 01:10:26.365463 31935 exec.cpp:382] Executor sending status update > for task 0 in state TASK_RUNNING > I0321 01:10:26.366636 31935 exec.cpp:382] Executor sending status update > for task 1 in state TASK_RUNNING > I0321 01:10:26.366665 31941 slave.cpp:1216] Handling status update > TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.367187 31941 slave.cpp:1263] Forwarding status update > TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to the status update manager > I0321 01:10:26.367769 31938 status_update_manager.cpp:253] Received status > update TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.367775 31941 slave.cpp:1216] Handling status update > TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.369221 31938 status_update_manager.cpp:402] Creating > StatusUpdate stream for task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.370157 31938 status_update_manager.hpp:314] Handling UPDATE > for status update TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.370636 31938 status_update_manager.cpp:288] Forwarding > status update TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to the master at > [email protected]:52923 > I0321 01:10:26.369699 31941 slave.cpp:1263] Forwarding status update > TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 to the status update manager > I0321 01:10:26.371270 31937 master.cpp:1086] Status update from (734)@ > 67.195.138.60:52923: task 0 of framework > 201303210110-1015726915-52923-31915-0000 is now in state TASK_RUNNING > I0321 01:10:26.371739 31941 slave.cpp:1325] Sending ACK for status update > TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to executor executor(25)@ > 67.195.138.60:52923 > I0321 01:10:26.371743 31942 status_update_manager.cpp:253] Received status > update TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.372236 31936 sched.cpp:327] Received status update > TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 from slave(79)@ > 67.195.138.60:52923 > I0321 01:10:26.372889 31937 exec.cpp:289] Executor received ACK for status > update of task 0 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.374078 31942 status_update_manager.cpp:402] Creating > StatusUpdate stream for task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.375207 31936 sched.cpp:360] Sending ACK for status update > TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to slave(79)@67.195.138.60:52923 > I0321 01:10:26.377210 31936 slave.cpp:991] Got acknowledgement of status > update for task 0 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.376005 31942 status_update_manager.hpp:314] Handling UPDATE > for status update TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.378142 31942 status_update_manager.cpp:288] Forwarding > status update TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 to the master at > [email protected]:52923 > I0321 01:10:26.378666 31942 status_update_manager.cpp:313] Received status > update acknowledgement for task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.378701 31941 master.cpp:1086] Status update from (734)@ > 67.195.138.60:52923: task 1 of framework > 201303210110-1015726915-52923-31915-0000 is now in state TASK_RUNNING > I0321 01:10:26.378705 31940 slave.cpp:1325] Sending ACK for status update > TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 to executor executor(25)@ > 67.195.138.60:52923 > I0321 01:10:26.379886 31942 status_update_manager.hpp:314] Handling ACK > for status update TASK_RUNNING from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.380398 31935 sched.cpp:327] Received status update > TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 from slave(79)@ > 67.195.138.60:52923 > I0321 01:10:26.380852 31941 exec.cpp:289] Executor received ACK for status > update of task 1 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.382591 31935 sched.cpp:360] Sending ACK for status update > TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 to slave(79)@67.195.138.60:52923 > I0321 01:10:26.383736 31938 slave.cpp:991] Got acknowledgement of status > update for task 1 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.384263 31938 status_update_manager.cpp:313] Received status > update acknowledgement for task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:26.384610 31938 status_update_manager.hpp:314] Handling ACK > for status update TASK_RUNNING from task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.341601 31938 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=1; mem=512; ports=[31000-32000]; disk=14049 on > slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:27.341691 31938 hierarchical_allocator_process.hpp:686] > Offering cpus=1; mem=512; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.342162 31938 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 581.90us > I0321 01:10:27.342226 31936 master.hpp:309] Adding offer with resources > cpus=1; mem=512; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:27.342991 31936 master.cpp:1327] Sending 1 offers to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.343497 31940 sched.cpp:282] Received 1 offers > I0321 01:10:27.344004 31940 master.cpp:1534] Processing reply for offer > 201303210110-1015726915-52923-31915-1 on slave > 201303210110-1015726915-52923-31915-0 (janus.apache.org) for framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.344472 31940 master.hpp:318] Removing offer with resources > cpus=1; mem=512; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:27.344563 31935 hierarchical_allocator_process.hpp:471] > Framework 201303210110-1015726915-52923-31915-0000 left cpus=1; mem=512; > ports=[31000-32000]; disk=14049 unused on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:27.345899 31935 hierarchical_allocator_process.hpp:497] > Framework 201303210110-1015726915-52923-31915-0000 filtered slave > 201303210110-1015726915-52923-31915-0 for 1.00secs > I0321 01:10:27.344043 31937 exec.cpp:382] Executor sending status update > for task 0 in state TASK_FINISHED > I0321 01:10:27.347534 31937 slave.cpp:1216] Handling status update > TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > ../../src/tests/allocator_tests.cpp:1139: Failure > Mock function called more times than expected - returning directly. > Function call: resourcesChanged(@0x2ad15c001ed8 > 201303210110-1015726915-52923-31915-0000, @0x2ad15c001eb8 default, > @0x2ad15c001e80 { cpus=1, mem=256 }) > Expected: to be called twice > Actual: called 3 times - over-saturated and active > I0321 01:10:27.347615 31937 slave.cpp:1263] Forwarding status update > TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to the status update manager > I0321 01:10:27.347980 31937 status_update_manager.cpp:253] Received status > update TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.348350 31937 status_update_manager.hpp:314] Handling UPDATE > for status update TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.348825 31937 status_update_manager.cpp:288] Forwarding > status update TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to the master at > [email protected]:52923 > I0321 01:10:27.349365 31937 master.cpp:1086] Status update from (734)@ > 67.195.138.60:52923: task 0 of framework > 201303210110-1015726915-52923-31915-0000 is now in state TASK_FINISHED > I0321 01:10:27.349441 31939 slave.cpp:1325] Sending ACK for status update > TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to executor executor(25)@ > 67.195.138.60:52923 > I0321 01:10:27.349831 31937 master.hpp:300] Removing task with resources > cpus=1; mem=256 on slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:27.349843 31938 sched.cpp:327] Received status update > TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 from slave(79)@ > 67.195.138.60:52923 > I0321 01:10:27.352282 31938 sched.cpp:360] Sending ACK for status update > TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 to slave(79)@67.195.138.60:52923 > I0321 01:10:27.352726 31938 slave.cpp:991] Got acknowledgement of status > update for task 0 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.350308 31936 exec.cpp:289] Executor received ACK for status > update of task 0 of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.350852 31940 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; > ports=[31000-32000]; disk=14049) on slave > 201303210110-1015726915-52923-31915-0 from framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.353274 31935 status_update_manager.cpp:313] Received status > update acknowledgement for task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.354802 31935 status_update_manager.hpp:314] Handling ACK > for status update TASK_FINISHED from task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.355379 31935 status_update_manager.cpp:433] Cleaning up > status update stream for task 0 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:27.594573 31942 monitor.cpp:206] Publishing resource usage for > executor 'default' of framework '201303210110-1015726915-52923-31915-0000' > I0321 01:10:27.671926 31942 monitor.cpp:206] Publishing resource usage for > executor 'default' of framework '201303210110-1015726915-52923-31915-0000' > I0321 01:10:28.228677 31942 monitor.cpp:206] Publishing resource usage for > executor 'default' of framework '201303210110-1015726915-52923-31915-0000' > I0321 01:10:28.342949 31942 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=2; mem=768; ports=[31000-32000]; disk=14049 on > slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:28.343039 31942 hierarchical_allocator_process.hpp:686] > Offering cpus=2; mem=768; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.343487 31942 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 576.24us > I0321 01:10:28.343554 31936 master.hpp:309] Adding offer with resources > cpus=2; mem=768; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:28.344316 31936 master.cpp:1327] Sending 1 offers to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.344831 31938 sched.cpp:282] Received 1 offers > I0321 01:10:28.345420 31937 sched.cpp:422] Stopping framework > '201303210110-1015726915-52923-31915-0000' > I0321 01:10:28.345793 31936 master.cpp:774] Asked to unregister framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.346261 31936 master.hpp:300] Removing task with resources > cpus=1; mem=256 on slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:28.346273 31935 slave.cpp:901] Asked to shut down framework > 201303210110-1015726915-52923-31915-0000 by [email protected]:52923 > I0321 01:10:28.347188 31935 slave.cpp:906] Shutting down framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.346782 31936 master.hpp:318] Removing offer with resources > cpus=2; mem=768; ports=[31000-32000]; disk=14049 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:28.346315 31940 hierarchical_allocator_process.hpp:359] > Deactivated framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.348675 31940 hierarchical_allocator_process.hpp:544] > Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; > disk=0) on slave 201303210110-1015726915-52923-31915-0 from framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.349171 31940 hierarchical_allocator_process.hpp:544] > Recovered cpus=2; mem=768; ports=[31000-32000]; disk=14049 (total > allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=14049) on slave > 201303210110-1015726915-52923-31915-0 from framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.349663 31940 hierarchical_allocator_process.hpp:312] > Removed framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.347652 31935 slave.cpp:1645] Shutting down executor > 'default' of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.350584 31936 exec.cpp:321] Executor asked to shutdown > I0321 01:10:28.350594 31935 status_update_manager.cpp:232] Closing status > update streams for framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.351163 31941 slave.cpp:386] Slave terminating > I0321 01:10:28.351544 31935 status_update_manager.cpp:433] Cleaning up > status update stream for task 1 of framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.352043 31941 slave.cpp:901] Asked to shut down framework > 201303210110-1015726915-52923-31915-0000 by @0.0.0.0:0 > I0321 01:10:28.353875 31941 slave.cpp:906] Shutting down framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.354343 31941 slave.cpp:1645] Shutting down executor > 'default' of framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.354922 31941 status_update_manager.cpp:232] Closing status > update streams for framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.354938 31937 master.cpp:537] Slave > 201303210110-1015726915-52923-31915-0(janus.apache.org) disconnected > I0321 01:10:28.356609 31937 master.cpp:542] Removing disconnected slave > 201303210110-1015726915-52923-31915-0(janus.apache.org) because it is not > checkpointing! > I0321 01:10:28.357228 31915 master.cpp:477] Master terminating > I0321 01:10:28.358006 31915 master.cpp:283] Shutting down master > [ FAILED ] AllocatorTest/0.TaskFinished, where TypeParam = > mesos::internal::master::HierarchicalAllocatorProcess<mesos::internal::master::DRFSorter, > mesos::internal::master::DRFSorter> (2020 ms) > [ RUN ] AllocatorTest/0.WhitelistSlave > I0321 01:10:28.379492 31936 master.cpp:309] Master started on > 67.195.138.60:52923 > I0321 01:10:28.379549 31936 master.cpp:324] Master ID: > 201303210110-1015726915-52923-31915 > I0321 01:10:28.380180 31940 hierarchical_allocator_process.hpp:236] > Initializing hierarchical allocator process with master : > [email protected]:52923 > I0321 01:10:28.380491 31940 hierarchical_allocator_process.hpp:437] > Updated slave white list: > I0321 01:10:28.380314 31939 master.cpp:603] Elected as master! > I0321 01:10:28.380262 31938 slave.cpp:203] Slave started on 80)@ > 67.195.138.60:52923 > I0321 01:10:28.381932 31938 slave.cpp:204] Slave resources: cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0321 01:10:28.380952 31940 hierarchical_allocator_process.hpp:439] > dummy-slave > I0321 01:10:28.380611 31935 sched.cpp:182] New master at > [email protected]:52923 > I0321 01:10:28.382757 31938 slave.cpp:453] New master detected at > [email protected]:52923 > I0321 01:10:28.383909 31938 slave.cpp:377] Finished recovery > I0321 01:10:28.383924 31935 status_update_manager.cpp:131] New master > detected at [email protected]:52923 > I0321 01:10:28.383524 31942 master.cpp:646] Registering framework > 201303210110-1015726915-52923-31915-0000 at scheduler(70)@ > 67.195.138.60:52923 > I0321 01:10:28.385437 31936 sched.cpp:217] Framework registered with > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.385443 31942 master.cpp:968] Attempting to register slave > on janus.apache.org at slave(80)@67.195.138.60:52923 > I0321 01:10:28.385478 31940 hierarchical_allocator_process.hpp:268] Added > framework 201303210110-1015726915-52923-31915-0000 > I0321 01:10:28.386381 31942 master.cpp:1224] Master now considering a > slave at janus.apache.org:52923 as active > I0321 01:10:28.386950 31940 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:28.387454 31942 master.cpp:1862] Adding slave > 201303210110-1015726915-52923-31915-0 at janus.apache.org with cpus=2; > mem=1024; ports=[31000-32000]; disk=1024 > I0321 01:10:28.387931 31940 hierarchical_allocator_process.hpp:599] > Performed allocation for 0 slaves in 981.59us > I0321 01:10:28.389508 31940 hierarchical_allocator_process.hpp:395] Added > slave 201303210110-1015726915-52923-31915-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) > I0321 01:10:28.389874 31940 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:28.390337 31940 hierarchical_allocator_process.hpp:619] > Performed allocation for slave 201303210110-1015726915-52923-31915-0 in > 472.22us > I0321 01:10:28.388478 31937 slave.cpp:487] Registered with master; given > slave ID 201303210110-1015726915-52923-31915-0 > I0321 01:10:29.380611 31937 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:29.380656 31937 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 64.75us > I0321 01:10:30.382899 31939 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:30.383014 31939 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 158.61us > I0321 01:10:31.384150 31941 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:31.384207 31941 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 94.09us > I0321 01:10:32.385526 31936 hierarchical_allocator_process.hpp:668] No > resources available to allocate! > I0321 01:10:32.385609 31936 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 118.89us > I0321 01:10:33.381067 31935 hierarchical_allocator_process.hpp:437] > Updated slave white list: > I0321 01:10:33.381172 31935 hierarchical_allocator_process.hpp:439] > dummy-slave > I0321 01:10:33.381873 31935 hierarchical_allocator_process.hpp:439] > janus.apache.org > I0321 01:10:33.386939 31938 hierarchical_allocator_process.hpp:660] Found > available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on > slave 201303210110-1015726915-52923-31915-0 > I0321 01:10:33.425842 31938 hierarchical_allocator_process.hpp:686] > Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201303210110-1015726915-52923-31915-0 to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:33.453912 31938 hierarchical_allocator_process.hpp:599] > Performed allocation for 1 slaves in 67.02ms > I0321 01:10:33.454049 31942 master.hpp:309] Adding offer with resources > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:33.454653 31942 master.cpp:1327] Sending 1 offers to framework > 201303210110-1015726915-52923-31915-0000 > I0321 01:10:33.455615 31940 sched.cpp:282] Received 1 offers > I0321 01:10:33.482259 31915 slave.cpp:386] Slave terminating > I0321 01:10:33.482305 31942 sched.cpp:422] Stopping framework > '201303210110-1015726915-52923-31915-0000' > I0321 01:10:33.482671 31939 master.cpp:537] Slave > 201303210110-1015726915-52923-31915-0(janus.apache.org) disconnected > I0321 01:10:33.483556 31939 master.cpp:542] Removing disconnected slave > 201303210110-1015726915-52923-31915-0(janus.apache.org) because it is not > checkpointing! > I0321 01:10:33.484153 31939 master.hpp:318] Removing offer with resources > cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave > 201303210110-1015726915-52923-31915-0 > I0321 01:10:33.485046 31939 master.cpp:477] Master terminating > I0321 01:10:33.486016 31915 master.cpp:283] Shutting down master > [ OK ] AllocatorTest/0.WhitelistSlave (5128 ms) > [----------] 9 tests from AllocatorTest/0 (10963 ms total) > > [----------] 1 test from LoggingTest > [ RUN ] LoggingTest.Toggle > I0321 01:10:33.487167 31941 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.487968 31943 process.cpp:878] Socket closed while receiving > I0321 01:10:33.488690 31942 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.489008 31943 process.cpp:878] Socket closed while receiving > I0321 01:10:33.489955 31939 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.490566 31943 process.cpp:878] Socket closed while receiving > I0321 01:10:33.491052 31940 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.492136 31943 process.cpp:878] Socket closed while receiving > I0321 01:10:33.507220 31939 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.534643 31943 process.cpp:878] Socket closed while receiving > I0321 01:10:33.556000 31938 process.cpp:2931] Handling HTTP event for > process 'logging' with path: '/logging/toggle' > I0321 01:10:33.562717 31943 process.cpp:878] Socket closed while receiving > [ OK ] LoggingTest.Toggle (77 ms) > [----------] 1 test from LoggingTest (77 ms total) > > [----------] 5 tests from CgroupsCpusetTest > [ RUN ] CgroupsCpusetTest.OneCPUOneCpuset > [ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms) > [ RUN ] CgroupsCpusetTest.OneCPUManyCpusets > [ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms) > [ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset > [ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms) > [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets > [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) > [ RUN ] CgroupsCpusetTest.IntegerAllocations > [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) > [----------] 5 tests from CgroupsCpusetTest (0 ms total) > > [----------] 3 tests from FsTest > [ RUN ] FsTest.MountTableRead > [ OK ] FsTest.MountTableRead (37 ms) > [ RUN ] FsTest.MountTableHasOption > [ OK ] FsTest.MountTableHasOption (0 ms) > [ RUN ] FsTest.FileSystemTableRead > [ OK ] FsTest.FileSystemTableRead (28 ms) > [----------] 3 tests from FsTest (66 ms total) > > [----------] 4 tests from ProcTest > [ RUN ] ProcTest.Pids > [ OK ] ProcTest.Pids (1 ms) > [ RUN ] ProcTest.Cpus > [ OK ] ProcTest.Cpus (0 ms) > [ RUN ] ProcTest.SystemStatistics > [ OK ] ProcTest.SystemStatistics (0 ms) > [ RUN ] ProcTest.ProcessStatistics > [ OK ] ProcTest.ProcessStatistics (0 ms) > [----------] 4 tests from ProcTest (1 ms total) > > [----------] Global test environment tear-down > [==========] 171 tests from 37 test cases ran. (71508 ms total) > [ PASSED ] 170 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] AllocatorTest/0.TaskFinished, where TypeParam = > mesos::internal::master::HierarchicalAllocatorProcess<mesos::internal::master::DRFSorter, > mesos::internal::master::DRFSorter> > > 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-Disable-Java-Disable-Python-Disable-Webui/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-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make[1]: *** [check] Error 2 > make[1]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make: *** [check-recursive] Error 1 > Process leaked file descriptors. See > http://wiki.jenkins-ci.org/display/JENKINS/Spawning+processes+from+buildfor > more information > Build step 'Execute shell' marked build as failure >
