See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/315/changes>
Changes: [benh] Fixed bug creating SVN tag in release.sh. Review: https://reviews.apache.org/r/10767 [benh] Removed use of AWAIT_*_FOR since defaults have been increased. Review: https://reviews.apache.org/r/10765 [benh] Increased default duration to await on futures when testing. Review: https://reviews.apache.org/r/10764 [benh] Missed refactors for new testing abstractions after rebase. [benh] Fixed a comment. Review: https://reviews.apache.org/r/10761 [benh] Updated output of libprocess gtest abstractions. Review: https://reviews.apache.org/r/10760 [benh] Updated some tests to use new testing abstractions. Review: https://reviews.apache.org/r/10759 [benh] Added a Socket reference to Encoder. Review: https://reviews.apache.org/r/10742 [benh] Increased timeouts used in log tests to avoid test failures due to slow hardware or resource contention from other processes. Review: https://reviews.apache.org/r/10464 [benh] Used libprocess testing abstractions in log tests. Review: https://reviews.apache.org/r/10463 [benh] Fixed bug in test where Scheduler::error might not have been invoked before we attempted to stop the scheduler driver. Review: https://reviews.apache.org/r/10462 [benh] Replaced libprocess specific testing abstractions. Review: https://reviews.apache.org/r/10461 [benh] Renamed and improved testing abstractions for awaiting on futures. Review: https://reviews.apache.org/r/10460 [benh] Updated some variable naming to be consistent. Review: https://reviews.apache.org/r/10459 [benh] Removed or replaced use of ProcessIsolator with TestingIsolator. Review: https://reviews.apache.org/r/10458 [benh] Removed unused testing utilities. Review: https://reviews.apache.org/r/10457 [benh] Cleaned up fault tolerant tests and fixed a disabled test. Review: https://reviews.apache.org/r/10456 ------------------------------------------ [...truncated 14061 lines...] I0425 05:43:22.705091 16663 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 468.636000000000024us I0425 05:43:22.704150 16658 master.cpp:1224] Master now considering a slave at janus.apache.org:43815 as active I0425 05:43:22.706063 16658 master.cpp:1885] Adding slave 201304250543-1015726915-43815-16581-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=13799 I0425 05:43:22.706575 16662 slave.cpp:590] Registered with master [email protected]:43815; given slave ID 201304250543-1015726915-43815-16581-0 I0425 05:43:22.706732 16658 hierarchical_allocator_process.hpp:395] Added slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=13799 (and cpus=3; mem=1024; ports=[31000-32000]; disk=13799 available) I0425 05:43:22.708606 16658 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.709053 16658 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.709674 16658 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304250543-1015726915-43815-16581-0 in 1.074961000000000ms I0425 05:43:22.709727 16662 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.710639 16662 master.cpp:1327] Sending 1 offers to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.711164 16657 sched.cpp:282] Received 1 offers I0425 05:43:22.711884 16661 master.cpp:1556] Processing reply for offer 201304250543-1015726915-43815-16581-0 on slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.712255 16661 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.712704 16661 master.cpp:1674] Launching task 0 of framework 201304250543-1015726915-43815-16581-0000 with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) I0425 05:43:22.713314 16661 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.713327 16662 slave.cpp:719] Got assigned task 0 for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.713778 16661 master.cpp:1674] Launching task 1 of framework 201304250543-1015726915-43815-16581-0000 with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) I0425 05:43:22.714896 16661 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.714485 16662 slave.cpp:817] Launching task 0 for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.714939 16663 hierarchical_allocator_process.hpp:471] Framework 201304250543-1015726915-43815-16581-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=13799 unused on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.716990 16662 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_2Ras4U/slaves/201304250543-1015726915-43815-16581-0/frameworks/201304250543-1015726915-43815-16581-0000/executors/default/runs/d78a9200-df2e-45d9-ac21-9b2656e7b4b6' I0425 05:43:22.717155 16662 slave.cpp:928] Queuing task '0' for executor default of framework '201304250543-1015726915-43815-16581-0000 I0425 05:43:22.717473 16662 slave.cpp:719] Got assigned task 1 for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.717322 16663 exec.cpp:170] Executor started at: executor(30)@67.195.138.60:43815 with pid 16581 I0425 05:43:22.718040 16662 slave.cpp:512] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_2Ras4U/slaves/201304250543-1015726915-43815-16581-0/frameworks/201304250543-1015726915-43815-16581-0000/executors/default/runs/d78a9200-df2e-45d9-ac21-9b2656e7b4b6' I0425 05:43:22.718992 16662 slave.cpp:817] Launching task 1 for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.719498 16662 slave.cpp:928] Queuing task '1' for executor default of framework '201304250543-1015726915-43815-16581-0000 I0425 05:43:22.719998 16662 slave.cpp:1385] Got registration for executor 'default' of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.720617 16662 slave.cpp:1500] Flushing queued task 1 for executor 'default' of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.721048 16662 slave.cpp:1500] Flushing queued task 0 for executor 'default' of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.720635 16658 exec.cpp:194] Executor registered on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.722061 16658 exec.cpp:258] Executor asked to run task '1' I0425 05:43:22.722566 16658 exec.cpp:258] Executor asked to run task '0' I0425 05:43:22.723002 16658 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING I0425 05:43:22.724639 16658 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING I0425 05:43:22.724663 16656 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.725111 16659 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 with checkpoint=false I0425 05:43:22.725500 16659 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.726035 16659 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to the master at [email protected]:43815 I0425 05:43:22.725654 16658 slave.cpp:1729] Handling status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.726584 16663 master.cpp:1086] Status update from (761)@67.195.138.60:43815: task 1 of framework 201304250543-1015726915-43815-16581-0000 is now in state TASK_RUNNING I0425 05:43:22.727107 16658 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.727126 16657 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 with checkpoint=false I0425 05:43:22.728318 16663 sched.cpp:327] Received status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 from slave(89)@67.195.138.60:43815 I0425 05:43:22.729202 16658 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to executor executor(30)@67.195.138.60:43815 I0425 05:43:22.729651 16657 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.730182 16663 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to slave(89)@67.195.138.60:43815 I0425 05:43:22.730710 16661 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.731173 16657 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 to the master at [email protected]:43815 I0425 05:43:22.731642 16663 slave.cpp:1292] Got status update acknowledgement 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.750844 16658 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.813057 16656 master.cpp:1086] Status update from (761)@67.195.138.60:43815: task 0 of framework 201304250543-1015726915-43815-16581-0000 is now in state TASK_RUNNING I0425 05:43:22.814440 16656 sched.cpp:327] Received status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 from slave(89)@67.195.138.60:43815 I0425 05:43:22.813489 16660 status_update_manager.cpp:360] Received status update acknowledgement 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.813973 16658 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.813467 16663 slave.cpp:1786] Status update manager successfully handled status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.816324 16663 slave.cpp:1792] Sending acknowledgement for status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 to executor executor(30)@67.195.138.60:43815 I0425 05:43:22.815968 16658 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 65.145745000000005ms I0425 05:43:22.816025 16660 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.817790 16660 master.cpp:1327] Sending 1 offers to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.816808 16663 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 7eb0d859-44ff-4ceb-8ebf-30ada707ea1b for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.816829 16657 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.814898 16656 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a) for task 0 of framework 201304250543-1015726915-43815-16581-0000 to slave(89)@67.195.138.60:43815 I0425 05:43:22.820251 16656 sched.cpp:282] Received 1 offers I0425 05:43:22.820268 16662 slave.cpp:1292] Got status update acknowledgement 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.820734 16659 master.cpp:1556] Processing reply for offer 201304250543-1015726915-43815-16581-1 on slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) for framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.820842 16656 exec.cpp:382] Executor sending status update for task 1 in state TASK_FINISHED I0425 05:43:22.821156 16662 status_update_manager.cpp:360] Received status update acknowledgement 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.823716 16662 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 15e2a8d7-5bf2-4946-b47b-8aeffc87ff4a for task 0 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.821707 16657 hierarchical_allocator_process.hpp:471] Framework 201304250543-1015726915-43815-16581-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=13799 unused on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.821682 16659 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.824199 16662 slave.cpp:1729] Handling status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.825911 16663 status_update_manager.cpp:289] Received status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 with checkpoint=false I0425 05:43:22.826282 16663 status_update_manager.cpp:335] Forwarding status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to the master at [email protected]:43815 I0425 05:43:22.826860 16663 slave.cpp:1786] Status update manager successfully handled status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.827267 16663 slave.cpp:1792] Sending acknowledgement for status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to executor executor(30)@67.195.138.60:43815 I0425 05:43:22.826915 16662 master.cpp:1086] Status update from (761)@67.195.138.60:43815: task 1 of framework 201304250543-1015726915-43815-16581-0000 is now in state TASK_FINISHED I0425 05:43:22.827929 16658 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.828408 16662 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.828415 16663 sched.cpp:327] Received status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 from slave(89)@67.195.138.60:43815 I0425 05:43:22.829545 16661 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=13799) on slave 201304250543-1015726915-43815-16581-0 from framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.829936 16663 sched.cpp:360] Sending ACK for status update TASK_FINISHED (UUID: 0e32a68d-b587-4afc-a89c-f2ef528da75d) for task 1 of framework 201304250543-1015726915-43815-16581-0000 to slave(89)@67.195.138.60:43815 I0425 05:43:22.830950 16663 slave.cpp:1292] Got status update acknowledgement 0e32a68d-b587-4afc-a89c-f2ef528da75d for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.831558 16658 status_update_manager.cpp:360] Received status update acknowledgement 0e32a68d-b587-4afc-a89c-f2ef528da75d for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.832610 16658 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.833113 16658 slave.cpp:1335] Status update manager successfully handled status update acknowledgement 0e32a68d-b587-4afc-a89c-f2ef528da75d for task 1 of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.868098 16663 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:22.868168 16663 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:22.899209 16663 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 31.133813000000004ms I0425 05:43:22.899269 16660 master.hpp:309] Adding offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.002543 16660 master.cpp:1327] Sending 1 offers to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.031121 16656 sched.cpp:282] Received 1 offers I0425 05:43:23.031920 16658 sched.cpp:422] Stopping framework '201304250543-1015726915-43815-16581-0000' I0425 05:43:23.032063 16661 master.cpp:774] Asked to unregister framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.032524 16658 slave.cpp:1099] Asked to shut down framework 201304250543-1015726915-43815-16581-0000 by [email protected]:43815 I0425 05:43:23.032549 16663 hierarchical_allocator_process.hpp:359] Deactivated framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.032554 16661 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.032973 16658 slave.cpp:1124] Shutting down framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.034430 16658 slave.cpp:2302] Shutting down executor 'default' of framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.034132 16657 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201304250543-1015726915-43815-16581-0 from framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.035475 16657 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=13799 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=13799) on slave 201304250543-1015726915-43815-16581-0 from framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.034919 16656 exec.cpp:321] Executor asked to shutdown I0425 05:43:23.034031 16661 master.hpp:318] Removing offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.043354 16581 master.cpp:477] Master terminating I0425 05:43:23.043532 16581 master.cpp:283] Shutting down master I0425 05:43:23.043531 16656 slave.cpp:486] Slave asked to shut down by [email protected]:43815 I0425 05:43:23.044134 16660 hierarchical_allocator_process.hpp:423] Removed slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.044457 16656 slave.cpp:1099] Asked to shut down framework 201304250543-1015726915-43815-16581-0000 by [email protected]:43815 W0425 05:43:23.045394 16656 slave.cpp:1120] Ignoring shutdown framework 201304250543-1015726915-43815-16581-0000 because it is terminating I0425 05:43:23.045897 16656 slave.cpp:441] Slave terminating I0425 05:43:23.046366 16656 slave.cpp:1099] Asked to shut down framework 201304250543-1015726915-43815-16581-0000 by @0.0.0.0:0 W0425 05:43:23.046866 16656 slave.cpp:1120] Ignoring shutdown framework 201304250543-1015726915-43815-16581-0000 because it is terminating I0425 05:43:23.051844 16661 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.103122 16661 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 51.289102000000000ms [ OK ] AllocatorTest/0.TaskFinished (451 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0425 05:43:23.134866 16659 master.cpp:309] Master started on 67.195.138.60:43815 I0425 05:43:23.134950 16659 master.cpp:324] Master ID: 201304250543-1015726915-43815-16581 I0425 05:43:23.135390 16660 slave.cpp:210] Slave started on 90)@67.195.138.60:43815 I0425 05:43:23.152130 16657 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : [email protected]:43815 I0425 05:43:23.152765 16657 hierarchical_allocator_process.hpp:437] Updated slave white list: I0425 05:43:23.152410 16661 sched.cpp:182] New master at [email protected]:43815 I0425 05:43:23.152406 16660 slave.cpp:211] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=13799 I0425 05:43:23.152302 16659 master.cpp:603] Elected as master! I0425 05:43:23.153216 16657 hierarchical_allocator_process.hpp:439] dummy-slave I0425 05:43:23.154793 16659 master.cpp:646] Registering framework 201304250543-1015726915-43815-16581-0000 at scheduler(80)@67.195.138.60:43815 I0425 05:43:23.155699 16661 sched.cpp:217] Framework registered with 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.155184 16657 hierarchical_allocator_process.hpp:632] No users to allocate resources! I0425 05:43:23.156617 16657 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.434983000000000ms I0425 05:43:23.157152 16657 hierarchical_allocator_process.hpp:268] Added framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.157625 16657 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.158129 16657 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 505.177000000000021us I0425 05:43:23.155715 16659 master.cpp:633] Framework 201304250543-1015726915-43815-16581-0000 (scheduler(80)@67.195.138.60:43815) already registered, resending acknowledgement I0425 05:43:23.154830 16660 slave.cpp:530] New master detected at [email protected]:43815 I0425 05:43:23.172045 16660 slave.cpp:545] Postponing registration until recovery is complete I0425 05:43:23.172510 16660 slave.cpp:391] Finished recovery I0425 05:43:23.172085 16662 status_update_manager.cpp:154] New master detected at [email protected]:43815 I0425 05:43:23.171521 16659 sched.cpp:212] Ignoring framework registered message because the driver is already connected! I0425 05:43:23.173136 16663 master.cpp:968] Attempting to register slave on janus.apache.org at slave(90)@67.195.138.60:43815 I0425 05:43:23.174438 16663 master.cpp:1224] Master now considering a slave at janus.apache.org:43815 as active I0425 05:43:23.174934 16663 master.cpp:1885] Adding slave 201304250543-1015726915-43815-16581-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=13799 I0425 05:43:23.175454 16656 slave.cpp:590] Registered with master [email protected]:43815; given slave ID 201304250543-1015726915-43815-16581-0 I0425 05:43:23.175537 16663 master.cpp:957] Slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) already registered, resending acknowledgement I0425 05:43:23.175631 16660 hierarchical_allocator_process.hpp:395] Added slave 201304250543-1015726915-43815-16581-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=13799 (and cpus=2; mem=1024; ports=[31000-32000]; disk=13799 available) I0425 05:43:23.176923 16660 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.177393 16660 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304250543-1015726915-43815-16581-0 in 483.368000000000052us I0425 05:43:23.182706 16663 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.182749 16663 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 53.947000000000003us I0425 05:43:23.192806 16662 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.192857 16662 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 109.069000000000017us I0425 05:43:23.263149 16659 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.263222 16659 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 125.532000000000011us I0425 05:43:23.273185 16661 hierarchical_allocator_process.hpp:668] No resources available to allocate! I0425 05:43:23.273233 16661 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 68.660000000000011us I0425 05:43:23.282392 16661 hierarchical_allocator_process.hpp:437] Updated slave white list: I0425 05:43:23.282795 16661 hierarchical_allocator_process.hpp:439] dummy-slave I0425 05:43:23.283257 16661 hierarchical_allocator_process.hpp:439] janus.apache.org I0425 05:43:23.293305 16658 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.293380 16658 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.304335 16658 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 11.087327000000000ms I0425 05:43:23.304406 16660 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.374493 16660 master.cpp:1327] Sending 1 offers to framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.374979 16663 sched.cpp:282] Received 1 offers I0425 05:43:23.383939 16662 sched.cpp:422] Stopping framework '201304250543-1015726915-43815-16581-0000' I0425 05:43:23.483886 16662 master.cpp:774] Asked to unregister framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.539165 16656 hierarchical_allocator_process.hpp:359] Deactivated framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.564630 16656 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=13799 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=13799) on slave 201304250543-1015726915-43815-16581-0 from framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.539232 16662 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=13799 on slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.539198 16657 slave.cpp:1099] Asked to shut down framework 201304250543-1015726915-43815-16581-0000 by [email protected]:43815 I0425 05:43:23.565670 16581 master.cpp:477] Master terminating I0425 05:43:23.566457 16581 master.cpp:283] Shutting down master W0425 05:43:23.565955 16657 slave.cpp:1114] Cannot shut down unknown framework 201304250543-1015726915-43815-16581-0000 I0425 05:43:23.567097 16663 hierarchical_allocator_process.hpp:423] Removed slave 201304250543-1015726915-43815-16581-0 I0425 05:43:23.567407 16657 slave.cpp:486] Slave asked to shut down by [email protected]:43815 I0425 05:43:23.568351 16657 slave.cpp:441] Slave terminating [ OK ] AllocatorTest/0.WhitelistSlave (435 ms) [----------] 9 tests from AllocatorTest/0 (6213 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0425 05:43:23.569807 16656 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.570368 16664 process.cpp:870] Socket closed while receiving I0425 05:43:23.570979 16658 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.571558 16664 process.cpp:870] Socket closed while receiving I0425 05:43:23.572022 16657 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.572635 16664 process.cpp:870] Socket closed while receiving I0425 05:43:23.573022 16658 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.573429 16664 process.cpp:870] Socket closed while receiving I0425 05:43:23.575053 16660 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.575412 16664 process.cpp:870] Socket closed while receiving I0425 05:43:23.575948 16660 process.cpp:2921] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0425 05:43:23.576380 16664 process.cpp:870] Socket closed while receiving [ OK ] LoggingTest.Toggle (7 ms) [----------] 1 test from LoggingTest (7 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 (1 ms) [ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets [ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms) [ RUN ] CgroupsCpusetTest.IntegerAllocations [ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms) [----------] 5 tests from CgroupsCpusetTest (1 ms total) [----------] 3 tests from FsTest [ RUN ] FsTest.MountTableRead [ OK ] FsTest.MountTableRead (0 ms) [ RUN ] FsTest.MountTableHasOption [ OK ] FsTest.MountTableHasOption (0 ms) [ RUN ] FsTest.FileSystemTableRead [ OK ] FsTest.FileSystemTableRead (60 ms) [----------] 3 tests from FsTest (60 ms total) [----------] 5 tests from ProcTest [ RUN ] ProcTest.pids [ OK ] ProcTest.pids (1 ms) [ RUN ] ProcTest.children [ OK ] ProcTest.children (17 ms) [ RUN ] ProcTest.cpus [ OK ] ProcTest.cpus (0 ms) [ RUN ] ProcTest.SystemStatus [ OK ] ProcTest.SystemStatus (0 ms) [ RUN ] ProcTest.ProcessStatus [ OK ] ProcTest.ProcessStatus (0 ms) [----------] 5 tests from ProcTest (18 ms total) [----------] Global test environment tear-down [==========] 173 tests from 36 test cases ran. (128108 ms total) [ PASSED ] 172 tests. [ FAILED ] 1 test, listed below: [ FAILED ] CoordinatorTest.TruncateLearnedFill 1 FAILED 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 Build step 'Execute shell' marked build as failure
