You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/04/15 05:18:18 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#304
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/304/changes>
Changes:
[bmahler] Replaced sleep() and usleep() calls with os::sleep().
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10443
[bmahler] Replaced usleep() calls in libprocess with os::sleep().
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10241
[bmahler] Changed os::sleep() to take a Duration instead of a double.
From: Jiang Yan Xu <ya...@jxu.me>
Review: https://reviews.apache.org/r/10231
------------------------------------------
[...truncated 13521 lines...]
I0415 03:18:15.913236 2917 hierarchical_allocator_process.hpp:660] Found available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.913281 2917 hierarchical_allocator_process.hpp:686] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0 to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.913363 2917 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304150318-453759884-55596-2866-0 in 134.48us
I0415 03:18:15.913388 2903 master.hpp:309] Adding offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.913475 2903 master.cpp:1327] Sending 1 offers to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.913573 2910 sched.cpp:282] Received 1 offers
I0415 03:18:15.913787 2918 master.cpp:1534] Processing reply for offer 201304150318-453759884-55596-2866-0 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.913882 2918 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.913931 2918 master.cpp:1651] Launching task 0 of framework 201304150318-453759884-55596-2866-0000 with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
I0415 03:18:15.914011 2915 slave.cpp:587] Got assigned task 0 for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.914033 2918 master.hpp:289] Adding task with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.914106 2918 master.cpp:1651] Launching task 1 of framework 201304150318-453759884-55596-2866-0000 with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
I0415 03:18:15.914240 2918 master.hpp:318] Removing offer with resources cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.914273 2916 hierarchical_allocator_process.hpp:471] Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=57022 unused on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.914942 2915 paths.hpp:302] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
I0415 03:18:15.915096 2915 slave.cpp:587] Got assigned task 1 for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.915132 2915 slave.cpp:671] Queuing task '1' for executor default of framework '201304150318-453759884-55596-2866-0000
I0415 03:18:15.915251 2903 exec.cpp:170] Executor started at: executor(25)@140.211.11.27:55596 with pid 2866
I0415 03:18:15.915268 2915 slave.cpp:436] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
I0415 03:18:15.915354 2915 slave.cpp:1046] Got registration for executor 'default' of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.915442 2915 slave.cpp:1121] Flushing queued tasks for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.915467 2920 exec.cpp:194] Executor registered on slave 201304150318-453759884-55596-2866-0
I0415 03:18:15.915563 2920 exec.cpp:258] Executor asked to run task '1'
I0415 03:18:15.915665 2920 exec.cpp:258] Executor asked to run task '0'
I0415 03:18:15.915822 2920 exec.cpp:382] Executor sending status update for task 1 in state TASK_RUNNING
I0415 03:18:15.916580 2920 exec.cpp:382] Executor sending status update for task 0 in state TASK_RUNNING
I0415 03:18:15.916599 2918 slave.cpp:1232] Handling status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.916682 2918 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000 to the status update manager
I0415 03:18:15.916764 2921 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.916803 2921 status_update_manager.cpp:403] Creating StatusUpdate stream for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.916858 2921 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.916901 2921 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000 to the master at master@140.211.11.27:55596
I0415 03:18:15.916998 2918 master.cpp:1086] Status update from (728)@140.211.11.27:55596: task 1 of framework 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
I0415 03:18:15.917028 2922 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000 to executor executor(25)@140.211.11.27:55596
I0415 03:18:15.917104 2914 sched.cpp:327] Received status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
I0415 03:18:15.917176 2914 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
I0415 03:18:15.917233 2918 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917300 2918 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917336 2918 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917394 2918 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917529 2920 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917542 2915 slave.cpp:1232] Handling status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917613 2915 slave.cpp:1280] Forwarding status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000 to the status update manager
I0415 03:18:15.917690 2915 status_update_manager.cpp:254] Received status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917719 2915 status_update_manager.cpp:403] Creating StatusUpdate stream for task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917764 2915 status_update_manager.hpp:314] Handling UPDATE for status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.917842 2915 status_update_manager.cpp:289] Forwarding status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000 to the master at master@140.211.11.27:55596
I0415 03:18:15.917922 2907 slave.cpp:1342] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000 to executor executor(25)@140.211.11.27:55596
I0415 03:18:15.917939 2913 master.cpp:1086] Status update from (728)@140.211.11.27:55596: task 0 of framework 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
I0415 03:18:15.918010 2918 exec.cpp:289] Executor received ACK for status update of task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.918071 2906 sched.cpp:327] Received status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
I0415 03:18:15.918139 2906 sched.cpp:360] Sending ACK for status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
I0415 03:18:15.918198 2906 slave.cpp:979] Got acknowledgement of status update for task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.918287 2915 status_update_manager.cpp:314] Received status update acknowledgement for task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.918326 2915 status_update_manager.hpp:314] Handling ACK for status update TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:15.918392 2915 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.912462 2922 hierarchical_allocator_process.hpp:660] Found available resources: cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:16.912578 2922 hierarchical_allocator_process.hpp:686] Offering cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0 to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.912758 2922 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 335.33us
I0415 03:18:16.912857 2905 master.hpp:309] Adding offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:16.913120 2905 master.cpp:1327] Sending 1 offers to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.913333 2905 sched.cpp:282] Received 1 offers
I0415 03:18:16.913681 2902 master.cpp:1534] Processing reply for offer 201304150318-453759884-55596-2866-1 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.913811 2902 master.hpp:318] Removing offer with resources cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:16.913959 2901 exec.cpp:382] Executor sending status update for task 1 in state TASK_FINISHED
I0415 03:18:16.914007 2914 hierarchical_allocator_process.hpp:471] Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=57022 unused on slave 201304150318-453759884-55596-2866-0
I0415 03:18:16.915129 2901 slave.cpp:1232] Handling status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.915258 2901 slave.cpp:1280] Forwarding status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000 to the status update manager
I0415 03:18:16.915374 2905 status_update_manager.cpp:254] Received status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.915477 2905 status_update_manager.hpp:314] Handling UPDATE for status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.915529 2905 status_update_manager.cpp:289] Forwarding status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000 to the master at master@140.211.11.27:55596
I0415 03:18:16.915616 2913 master.cpp:1086] Status update from (728)@140.211.11.27:55596: task 1 of framework 201304150318-453759884-55596-2866-0000 is now in state TASK_FINISHED
I0415 03:18:16.915791 2913 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:16.915798 2923 slave.cpp:1342] Sending ACK for status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000 to executor executor(25)@140.211.11.27:55596
I0415 03:18:16.915802 2902 sched.cpp:327] Received status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
I0415 03:18:16.915993 2919 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=57022) on slave 201304150318-453759884-55596-2866-0 from framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916048 2921 exec.cpp:289] Executor received ACK for status update of task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916060 2902 sched.cpp:360] Sending ACK for status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
I0415 03:18:16.916378 2902 slave.cpp:979] Got acknowledgement of status update for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916436 2923 status_update_manager.cpp:314] Received status update acknowledgement for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916502 2923 status_update_manager.hpp:314] Handling ACK for status update TASK_FINISHED from task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916667 2923 status_update_manager.cpp:434] Cleaning up status update stream for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:16.916731 2923 slave.cpp:1016] Status update manager successfully handled status update acknowledgement for task 1 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.094455 5607 exec.cpp:83] Committing suicide by killing the process group
I0415 03:18:17.913380 2902 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.913491 2902 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0 to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.913616 2902 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 279.27us
I0415 03:18:17.913749 2901 master.hpp:309] Adding offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.913872 2901 master.cpp:1327] Sending 1 offers to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.914019 2909 sched.cpp:282] Received 1 offers
I0415 03:18:17.914553 2912 sched.cpp:422] Stopping framework '201304150318-453759884-55596-2866-0000'
I0415 03:18:17.914700 2903 master.cpp:774] Asked to unregister framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.914803 2903 master.hpp:300] Removing task with resources cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.914835 2906 slave.cpp:889] Asked to shut down framework 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
I0415 03:18:17.914888 2903 master.hpp:318] Removing offer with resources cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.914907 2917 hierarchical_allocator_process.hpp:359] Deactivated framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.914983 2906 slave.cpp:894] Shutting down framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915192 2917 hierarchical_allocator_process.hpp:544] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201304150318-453759884-55596-2866-0 from framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915209 2906 slave.cpp:1681] Shutting down executor 'default' of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915338 2917 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=57022 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=57022) on slave 201304150318-453759884-55596-2866-0 from framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915355 2918 exec.cpp:321] Executor asked to shutdown
I0415 03:18:17.915359 2906 slave.cpp:386] Slave terminating
I0415 03:18:17.915395 2923 status_update_manager.cpp:233] Closing status update streams for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915529 2906 slave.cpp:889] Asked to shut down framework 201304150318-453759884-55596-2866-0000 by @0.0.0.0:0
I0415 03:18:17.915591 2923 status_update_manager.cpp:434] Cleaning up status update stream for task 0 of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915626 2906 slave.cpp:894] Shutting down framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915715 2906 slave.cpp:1681] Shutting down executor 'default' of framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915814 2906 status_update_manager.cpp:233] Closing status update streams for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.915827 2912 master.cpp:537] Slave 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
I0415 03:18:17.915956 2912 master.cpp:542] Removing disconnected slave 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not checkpointing!
I0415 03:18:17.916239 2866 master.cpp:477] Master terminating
I0415 03:18:17.916435 2866 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.TaskFinished (2006 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0415 03:18:17.918071 2916 master.cpp:309] Master started on 140.211.11.27:55596
I0415 03:18:17.918131 2916 master.cpp:324] Master ID: 201304150318-453759884-55596-2866
I0415 03:18:17.918467 2912 slave.cpp:203] Slave started on 81)@140.211.11.27:55596
I0415 03:18:17.918558 2908 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@140.211.11.27:55596
I0415 03:18:17.918627 2908 hierarchical_allocator_process.hpp:437] Updated slave white list:
I0415 03:18:17.918665 2908 hierarchical_allocator_process.hpp:439] dummy-slave
I0415 03:18:17.918735 2916 master.cpp:603] Elected as master!
I0415 03:18:17.918679 2912 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0415 03:18:17.918853 2910 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0415 03:18:17.918828 2903 sched.cpp:182] New master at master@140.211.11.27:55596
I0415 03:18:17.918905 2910 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 61.30us
I0415 03:18:17.919039 2917 master.cpp:646] Registering framework 201304150318-453759884-55596-2866-0000 at scheduler(73)@140.211.11.27:55596
I0415 03:18:17.919224 2912 slave.cpp:453] New master detected at master@140.211.11.27:55596
I0415 03:18:17.919284 2910 hierarchical_allocator_process.hpp:268] Added framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.919239 2900 sched.cpp:217] Framework registered with 201304150318-453759884-55596-2866-0000
I0415 03:18:17.919296 2912 slave.cpp:377] Finished recovery
I0415 03:18:17.919231 2917 master.cpp:633] Framework 201304150318-453759884-55596-2866-0000 (scheduler(73)@140.211.11.27:55596) already registered, resending acknowledgement
I0415 03:18:17.919304 2914 status_update_manager.cpp:132] New master detected at master@140.211.11.27:55596
I0415 03:18:17.919325 2910 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.919575 2917 sched.cpp:212] Ignoring framework registered message because the driver is already connected!
I0415 03:18:17.919586 2910 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 261.17us
I0415 03:18:17.919595 2906 master.cpp:968] Attempting to register slave on hemera.apache.org at slave(81)@140.211.11.27:55596
I0415 03:18:17.919742 2906 master.cpp:1224] Master now considering a slave at hemera.apache.org:55596 as active
I0415 03:18:17.919791 2906 master.cpp:1862] Adding slave 201304150318-453759884-55596-2866-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0415 03:18:17.919870 2900 slave.cpp:487] Registered with master; given slave ID 201304150318-453759884-55596-2866-0
I0415 03:18:17.919919 2906 master.cpp:957] Slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) already registered, resending acknowledgement
I0415 03:18:17.919980 2910 hierarchical_allocator_process.hpp:395] Added slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0415 03:18:17.919999 2920 slave.cpp:487] Registered with master; given slave ID 201304150318-453759884-55596-2866-0
I0415 03:18:17.920047 2910 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.920156 2910 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304150318-453759884-55596-2866-0 in 117.65us
I0415 03:18:17.929066 2906 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.929118 2906 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 68.58us
I0415 03:18:17.939112 2915 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.939164 2915 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 75.08us
I0415 03:18:17.949193 2905 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.949237 2905 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 60.86us
I0415 03:18:17.959239 2917 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0415 03:18:17.959280 2917 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 47.76us
I0415 03:18:17.959416 2917 hierarchical_allocator_process.hpp:437] Updated slave white list:
I0415 03:18:17.959487 2917 hierarchical_allocator_process.hpp:439] dummy-slave
I0415 03:18:17.959574 2917 hierarchical_allocator_process.hpp:439] hemera.apache.org
I0415 03:18:17.969391 2922 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.969445 2922 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304150318-453759884-55596-2866-0 to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.969552 2922 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 206.47us
I0415 03:18:17.969589 2911 master.hpp:309] Adding offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.969749 2911 master.cpp:1327] Sending 1 offers to framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.969852 2911 sched.cpp:282] Received 1 offers
I0415 03:18:17.979460 2923 sched.cpp:422] Stopping framework '201304150318-453759884-55596-2866-0000'
I0415 03:18:17.979574 2923 master.cpp:774] Asked to unregister framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.979686 2923 master.hpp:318] Removing offer with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304150318-453759884-55596-2866-0
I0415 03:18:17.979750 2900 hierarchical_allocator_process.hpp:359] Deactivated framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.979802 2914 slave.cpp:889] Asked to shut down framework 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
I0415 03:18:17.979941 2900 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304150318-453759884-55596-2866-0 from framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.979996 2907 status_update_manager.cpp:233] Closing status update streams for framework 201304150318-453759884-55596-2866-0000
I0415 03:18:17.980291 2920 slave.cpp:386] Slave terminating
I0415 03:18:17.980389 2911 master.cpp:537] Slave 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
I0415 03:18:17.980424 2911 master.cpp:542] Removing disconnected slave 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not checkpointing!
I0415 03:18:17.980610 2866 master.cpp:477] Master terminating
I0415 03:18:18.082686 2866 master.cpp:283] Shutting down master
[ OK ] AllocatorTest/0.WhitelistSlave (166 ms)
[----------] 9 tests from AllocatorTest/0 (4735 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0415 03:18:18.083878 2921 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.085011 2924 process.cpp:878] Socket closed while receiving
I0415 03:18:18.085589 2911 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.086385 2924 process.cpp:878] Socket closed while receiving
I0415 03:18:18.086869 2906 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.087815 2924 process.cpp:878] Socket closed while receiving
I0415 03:18:18.088340 2921 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.089082 2924 process.cpp:878] Socket closed while receiving
I0415 03:18:18.089656 2919 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.090312 2924 process.cpp:878] Socket closed while receiving
I0415 03:18:18.090739 2908 process.cpp:2931] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0415 03:18:18.091506 2924 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (8 ms)
[----------] 1 test from LoggingTest (8 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 (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 (6 ms)
[----------] 3 tests from FsTest (6 ms total)
[----------] 5 tests from ProcTest
[ RUN ] ProcTest.pids
[ OK ] ProcTest.pids (1 ms)
[ RUN ] ProcTest.children
[ OK ] ProcTest.children (15 ms)
[ RUN ] ProcTest.cpus
[ OK ] ProcTest.cpus (1 ms)
[ RUN ] ProcTest.SystemStatus
[ OK ] ProcTest.SystemStatus (0 ms)
[ RUN ] ProcTest.ProcessStatus
[ OK ] ProcTest.ProcessStatus (0 ms)
[----------] 5 tests from ProcTest (17 ms total)
[----------] Global test environment tear-down
[==========] 166 tests from 36 test cases ran. (16508 ms total)
[ PASSED ] 165 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MonitorTest.WatchUnwatch
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 `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#305
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/305/changes>
Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#304
Posted by Benjamin Mahler <be...@gmail.com>.
That being said, I do see some potential for flakiness so I'll attempt a
fix, and we'll see if it remedies the issue on jenkins.
On Mon, Apr 15, 2013 at 10:51 AM, Benjamin Mahler <benjamin.mahler@gmail.com
> wrote:
> Odd.. I'm unable to reproduce this test failure, I ran with:
>
> Linux: 35,000 iterations
> OSX: 10,000 iterations
>
> Both without any failures, I'm not sure what's special about this Jenkins
> machine that would be inducing the test failure.
>
>
> On Sun, Apr 14, 2013 at 8:18 PM, Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
>
>> See <
>> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/304/changes
>> >
>>
>> Changes:
>>
>> [bmahler] Replaced sleep() and usleep() calls with os::sleep().
>>
>> From: Jiang Yan Xu <ya...@jxu.me>
>> Review: https://reviews.apache.org/r/10443
>>
>> [bmahler] Replaced usleep() calls in libprocess with os::sleep().
>>
>> From: Jiang Yan Xu <ya...@jxu.me>
>> Review: https://reviews.apache.org/r/10241
>>
>> [bmahler] Changed os::sleep() to take a Duration instead of a double.
>>
>> From: Jiang Yan Xu <ya...@jxu.me>
>> Review: https://reviews.apache.org/r/10231
>>
>> ------------------------------------------
>> [...truncated 13521 lines...]
>> I0415 03:18:15.913236 2917 hierarchical_allocator_process.hpp:660] Found
>> available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on
>> slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.913281 2917 hierarchical_allocator_process.hpp:686]
>> Offering cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0 to framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.913363 2917 hierarchical_allocator_process.hpp:619]
>> Performed allocation for slave 201304150318-453759884-55596-2866-0 in
>> 134.48us
>> I0415 03:18:15.913388 2903 master.hpp:309] Adding offer with resources
>> cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.913475 2903 master.cpp:1327] Sending 1 offers to
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.913573 2910 sched.cpp:282] Received 1 offers
>> I0415 03:18:15.913787 2918 master.cpp:1534] Processing reply for offer
>> 201304150318-453759884-55596-2866-0 on slave
>> 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.913882 2918 master.hpp:289] Adding task with resources
>> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.913931 2918 master.cpp:1651] Launching task 0 of
>> framework 201304150318-453759884-55596-2866-0000 with resources cpus=1;
>> mem=256 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
>> I0415 03:18:15.914011 2915 slave.cpp:587] Got assigned task 0 for
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.914033 2918 master.hpp:289] Adding task with resources
>> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.914106 2918 master.cpp:1651] Launching task 1 of
>> framework 201304150318-453759884-55596-2866-0000 with resources cpus=1;
>> mem=256 on slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
>> I0415 03:18:15.914240 2918 master.hpp:318] Removing offer with resources
>> cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.914273 2916 hierarchical_allocator_process.hpp:471]
>> Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512;
>> ports=[31000-32000]; disk=57022 unused on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.914942 2915 paths.hpp:302] Created executor directory
>> '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
>> I0415 03:18:15.915096 2915 slave.cpp:587] Got assigned task 1 for
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.915132 2915 slave.cpp:671] Queuing task '1' for executor
>> default of framework '201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.915251 2903 exec.cpp:170] Executor started at:
>> executor(25)@140.211.11.27:55596 with pid 2866
>> I0415 03:18:15.915268 2915 slave.cpp:436] Successfully attached file
>> '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
>> I0415 03:18:15.915354 2915 slave.cpp:1046] Got registration for executor
>> 'default' of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.915442 2915 slave.cpp:1121] Flushing queued tasks for
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.915467 2920 exec.cpp:194] Executor registered on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:15.915563 2920 exec.cpp:258] Executor asked to run task '1'
>> I0415 03:18:15.915665 2920 exec.cpp:258] Executor asked to run task '0'
>> I0415 03:18:15.915822 2920 exec.cpp:382] Executor sending status update
>> for task 1 in state TASK_RUNNING
>> I0415 03:18:15.916580 2920 exec.cpp:382] Executor sending status update
>> for task 0 in state TASK_RUNNING
>> I0415 03:18:15.916599 2918 slave.cpp:1232] Handling status update
>> TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.916682 2918 slave.cpp:1280] Forwarding status update
>> TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to the status update manager
>> I0415 03:18:15.916764 2921 status_update_manager.cpp:254] Received
>> status update TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.916803 2921 status_update_manager.cpp:403] Creating
>> StatusUpdate stream for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.916858 2921 status_update_manager.hpp:314] Handling
>> UPDATE for status update TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.916901 2921 status_update_manager.cpp:289] Forwarding
>> status update TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to the master at
>> master@140.211.11.27:55596
>> I0415 03:18:15.916998 2918 master.cpp:1086] Status update from (728)@
>> 140.211.11.27:55596: task 1 of framework
>> 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
>> I0415 03:18:15.917028 2922 slave.cpp:1342] Sending ACK for status update
>> TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
>> 140.211.11.27:55596
>> I0415 03:18:15.917104 2914 sched.cpp:327] Received status update
>> TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
>> I0415 03:18:15.917176 2914 sched.cpp:360] Sending ACK for status update
>> TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
>> I0415 03:18:15.917233 2918 slave.cpp:979] Got acknowledgement of status
>> update for task 1 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917300 2918 status_update_manager.cpp:314] Received
>> status update acknowledgement for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917336 2918 status_update_manager.hpp:314] Handling ACK
>> for status update TASK_RUNNING from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917394 2918 slave.cpp:1016] Status update manager
>> successfully handled status update acknowledgement for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917529 2920 exec.cpp:289] Executor received ACK for
>> status update of task 1 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917542 2915 slave.cpp:1232] Handling status update
>> TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917613 2915 slave.cpp:1280] Forwarding status update
>> TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000 to the status update manager
>> I0415 03:18:15.917690 2915 status_update_manager.cpp:254] Received
>> status update TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917719 2915 status_update_manager.cpp:403] Creating
>> StatusUpdate stream for task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917764 2915 status_update_manager.hpp:314] Handling
>> UPDATE for status update TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.917842 2915 status_update_manager.cpp:289] Forwarding
>> status update TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000 to the master at
>> master@140.211.11.27:55596
>> I0415 03:18:15.917922 2907 slave.cpp:1342] Sending ACK for status update
>> TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
>> 140.211.11.27:55596
>> I0415 03:18:15.917939 2913 master.cpp:1086] Status update from (728)@
>> 140.211.11.27:55596: task 0 of framework
>> 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
>> I0415 03:18:15.918010 2918 exec.cpp:289] Executor received ACK for
>> status update of task 0 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.918071 2906 sched.cpp:327] Received status update
>> TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
>> I0415 03:18:15.918139 2906 sched.cpp:360] Sending ACK for status update
>> TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
>> I0415 03:18:15.918198 2906 slave.cpp:979] Got acknowledgement of status
>> update for task 0 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.918287 2915 status_update_manager.cpp:314] Received
>> status update acknowledgement for task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.918326 2915 status_update_manager.hpp:314] Handling ACK
>> for status update TASK_RUNNING from task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:15.918392 2915 slave.cpp:1016] Status update manager
>> successfully handled status update acknowledgement for task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.912462 2922 hierarchical_allocator_process.hpp:660] Found
>> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=57022 on
>> slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:16.912578 2922 hierarchical_allocator_process.hpp:686]
>> Offering cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0 to framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.912758 2922 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 335.33us
>> I0415 03:18:16.912857 2905 master.hpp:309] Adding offer with resources
>> cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:16.913120 2905 master.cpp:1327] Sending 1 offers to
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.913333 2905 sched.cpp:282] Received 1 offers
>> I0415 03:18:16.913681 2902 master.cpp:1534] Processing reply for offer
>> 201304150318-453759884-55596-2866-1 on slave
>> 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.913811 2902 master.hpp:318] Removing offer with resources
>> cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:16.913959 2901 exec.cpp:382] Executor sending status update
>> for task 1 in state TASK_FINISHED
>> I0415 03:18:16.914007 2914 hierarchical_allocator_process.hpp:471]
>> Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512;
>> ports=[31000-32000]; disk=57022 unused on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:16.915129 2901 slave.cpp:1232] Handling status update
>> TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.915258 2901 slave.cpp:1280] Forwarding status update
>> TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to the status update manager
>> I0415 03:18:16.915374 2905 status_update_manager.cpp:254] Received
>> status update TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.915477 2905 status_update_manager.hpp:314] Handling
>> UPDATE for status update TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.915529 2905 status_update_manager.cpp:289] Forwarding
>> status update TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to the master at
>> master@140.211.11.27:55596
>> I0415 03:18:16.915616 2913 master.cpp:1086] Status update from (728)@
>> 140.211.11.27:55596: task 1 of framework
>> 201304150318-453759884-55596-2866-0000 is now in state TASK_FINISHED
>> I0415 03:18:16.915791 2913 master.hpp:300] Removing task with resources
>> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:16.915798 2923 slave.cpp:1342] Sending ACK for status update
>> TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
>> 140.211.11.27:55596
>> I0415 03:18:16.915802 2902 sched.cpp:327] Received status update
>> TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
>> I0415 03:18:16.915993 2919 hierarchical_allocator_process.hpp:544]
>> Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768;
>> ports=[31000-32000]; disk=57022) on slave
>> 201304150318-453759884-55596-2866-0 from framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916048 2921 exec.cpp:289] Executor received ACK for
>> status update of task 1 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916060 2902 sched.cpp:360] Sending ACK for status update
>> TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
>> I0415 03:18:16.916378 2902 slave.cpp:979] Got acknowledgement of status
>> update for task 1 of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916436 2923 status_update_manager.cpp:314] Received
>> status update acknowledgement for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916502 2923 status_update_manager.hpp:314] Handling ACK
>> for status update TASK_FINISHED from task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916667 2923 status_update_manager.cpp:434] Cleaning up
>> status update stream for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:16.916731 2923 slave.cpp:1016] Status update manager
>> successfully handled status update acknowledgement for task 1 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.094455 5607 exec.cpp:83] Committing suicide by killing
>> the process group
>> I0415 03:18:17.913380 2902 hierarchical_allocator_process.hpp:660] Found
>> available resources: cpus=2; mem=768; ports=[31000-32000]; disk=57022 on
>> slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.913491 2902 hierarchical_allocator_process.hpp:686]
>> Offering cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0 to framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.913616 2902 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 279.27us
>> I0415 03:18:17.913749 2901 master.hpp:309] Adding offer with resources
>> cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.913872 2901 master.cpp:1327] Sending 1 offers to
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.914019 2909 sched.cpp:282] Received 1 offers
>> I0415 03:18:17.914553 2912 sched.cpp:422] Stopping framework
>> '201304150318-453759884-55596-2866-0000'
>> I0415 03:18:17.914700 2903 master.cpp:774] Asked to unregister framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.914803 2903 master.hpp:300] Removing task with resources
>> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.914835 2906 slave.cpp:889] Asked to shut down framework
>> 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
>> I0415 03:18:17.914888 2903 master.hpp:318] Removing offer with resources
>> cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.914907 2917 hierarchical_allocator_process.hpp:359]
>> Deactivated framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.914983 2906 slave.cpp:894] Shutting down framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915192 2917 hierarchical_allocator_process.hpp:544]
>> Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[];
>> disk=0) on slave 201304150318-453759884-55596-2866-0 from framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915209 2906 slave.cpp:1681] Shutting down executor
>> 'default' of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915338 2917 hierarchical_allocator_process.hpp:544]
>> Recovered cpus=2; mem=768; ports=[31000-32000]; disk=57022 (total
>> allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=57022) on slave
>> 201304150318-453759884-55596-2866-0 from framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915355 2918 exec.cpp:321] Executor asked to shutdown
>> I0415 03:18:17.915359 2906 slave.cpp:386] Slave terminating
>> I0415 03:18:17.915395 2923 status_update_manager.cpp:233] Closing status
>> update streams for framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915529 2906 slave.cpp:889] Asked to shut down framework
>> 201304150318-453759884-55596-2866-0000 by @0.0.0.0:0
>> I0415 03:18:17.915591 2923 status_update_manager.cpp:434] Cleaning up
>> status update stream for task 0 of framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915626 2906 slave.cpp:894] Shutting down framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915715 2906 slave.cpp:1681] Shutting down executor
>> 'default' of framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915814 2906 status_update_manager.cpp:233] Closing status
>> update streams for framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.915827 2912 master.cpp:537] Slave
>> 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
>> I0415 03:18:17.915956 2912 master.cpp:542] Removing disconnected slave
>> 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not
>> checkpointing!
>> I0415 03:18:17.916239 2866 master.cpp:477] Master terminating
>> I0415 03:18:17.916435 2866 master.cpp:283] Shutting down master
>> [ OK ] AllocatorTest/0.TaskFinished (2006 ms)
>> [ RUN ] AllocatorTest/0.WhitelistSlave
>> I0415 03:18:17.918071 2916 master.cpp:309] Master started on
>> 140.211.11.27:55596
>> I0415 03:18:17.918131 2916 master.cpp:324] Master ID:
>> 201304150318-453759884-55596-2866
>> I0415 03:18:17.918467 2912 slave.cpp:203] Slave started on 81)@
>> 140.211.11.27:55596
>> I0415 03:18:17.918558 2908 hierarchical_allocator_process.hpp:236]
>> Initializing hierarchical allocator process with master :
>> master@140.211.11.27:55596
>> I0415 03:18:17.918627 2908 hierarchical_allocator_process.hpp:437]
>> Updated slave white list:
>> I0415 03:18:17.918665 2908 hierarchical_allocator_process.hpp:439]
>> dummy-slave
>> I0415 03:18:17.918735 2916 master.cpp:603] Elected as master!
>> I0415 03:18:17.918679 2912 slave.cpp:204] Slave resources: cpus=2;
>> mem=1024; ports=[31000-32000]; disk=1024
>> I0415 03:18:17.918853 2910 hierarchical_allocator_process.hpp:632] No
>> users to allocate resources!
>> I0415 03:18:17.918828 2903 sched.cpp:182] New master at
>> master@140.211.11.27:55596
>> I0415 03:18:17.918905 2910 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 0 slaves in 61.30us
>> I0415 03:18:17.919039 2917 master.cpp:646] Registering framework
>> 201304150318-453759884-55596-2866-0000 at scheduler(73)@
>> 140.211.11.27:55596
>> I0415 03:18:17.919224 2912 slave.cpp:453] New master detected at
>> master@140.211.11.27:55596
>> I0415 03:18:17.919284 2910 hierarchical_allocator_process.hpp:268] Added
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.919239 2900 sched.cpp:217] Framework registered with
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.919296 2912 slave.cpp:377] Finished recovery
>> I0415 03:18:17.919231 2917 master.cpp:633] Framework
>> 201304150318-453759884-55596-2866-0000 (scheduler(73)@140.211.11.27:55596)
>> already registered, resending acknowledgement
>> I0415 03:18:17.919304 2914 status_update_manager.cpp:132] New master
>> detected at master@140.211.11.27:55596
>> I0415 03:18:17.919325 2910 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.919575 2917 sched.cpp:212] Ignoring framework registered
>> message because the driver is already connected!
>> I0415 03:18:17.919586 2910 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 0 slaves in 261.17us
>> I0415 03:18:17.919595 2906 master.cpp:968] Attempting to register slave
>> on hemera.apache.org at slave(81)@140.211.11.27:55596
>> I0415 03:18:17.919742 2906 master.cpp:1224] Master now considering a
>> slave at hemera.apache.org:55596 as active
>> I0415 03:18:17.919791 2906 master.cpp:1862] Adding slave
>> 201304150318-453759884-55596-2866-0 at hemera.apache.org with cpus=2;
>> mem=1024; ports=[31000-32000]; disk=1024
>> I0415 03:18:17.919870 2900 slave.cpp:487] Registered with master; given
>> slave ID 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.919919 2906 master.cpp:957] Slave
>> 201304150318-453759884-55596-2866-0 (hemera.apache.org) already
>> registered, resending acknowledgement
>> I0415 03:18:17.919980 2910 hierarchical_allocator_process.hpp:395] Added
>> slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) with
>> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024;
>> ports=[31000-32000]; disk=1024 available)
>> I0415 03:18:17.919999 2920 slave.cpp:487] Registered with master; given
>> slave ID 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.920047 2910 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.920156 2910 hierarchical_allocator_process.hpp:619]
>> Performed allocation for slave 201304150318-453759884-55596-2866-0 in
>> 117.65us
>> I0415 03:18:17.929066 2906 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.929118 2906 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 68.58us
>> I0415 03:18:17.939112 2915 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.939164 2915 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 75.08us
>> I0415 03:18:17.949193 2905 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.949237 2905 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 60.86us
>> I0415 03:18:17.959239 2917 hierarchical_allocator_process.hpp:668] No
>> resources available to allocate!
>> I0415 03:18:17.959280 2917 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 47.76us
>> I0415 03:18:17.959416 2917 hierarchical_allocator_process.hpp:437]
>> Updated slave white list:
>> I0415 03:18:17.959487 2917 hierarchical_allocator_process.hpp:439]
>> dummy-slave
>> I0415 03:18:17.959574 2917 hierarchical_allocator_process.hpp:439]
>> hemera.apache.org
>> I0415 03:18:17.969391 2922 hierarchical_allocator_process.hpp:660] Found
>> available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on
>> slave 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.969445 2922 hierarchical_allocator_process.hpp:686]
>> Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
>> 201304150318-453759884-55596-2866-0 to framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.969552 2922 hierarchical_allocator_process.hpp:599]
>> Performed allocation for 1 slaves in 206.47us
>> I0415 03:18:17.969589 2911 master.hpp:309] Adding offer with resources
>> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.969749 2911 master.cpp:1327] Sending 1 offers to
>> framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.969852 2911 sched.cpp:282] Received 1 offers
>> I0415 03:18:17.979460 2923 sched.cpp:422] Stopping framework
>> '201304150318-453759884-55596-2866-0000'
>> I0415 03:18:17.979574 2923 master.cpp:774] Asked to unregister framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.979686 2923 master.hpp:318] Removing offer with resources
>> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
>> 201304150318-453759884-55596-2866-0
>> I0415 03:18:17.979750 2900 hierarchical_allocator_process.hpp:359]
>> Deactivated framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.979802 2914 slave.cpp:889] Asked to shut down framework
>> 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
>> I0415 03:18:17.979941 2900 hierarchical_allocator_process.hpp:544]
>> Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total
>> allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave
>> 201304150318-453759884-55596-2866-0 from framework
>> 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.979996 2907 status_update_manager.cpp:233] Closing status
>> update streams for framework 201304150318-453759884-55596-2866-0000
>> I0415 03:18:17.980291 2920 slave.cpp:386] Slave terminating
>> I0415 03:18:17.980389 2911 master.cpp:537] Slave
>> 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
>> I0415 03:18:17.980424 2911 master.cpp:542] Removing disconnected slave
>> 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not
>> checkpointing!
>> I0415 03:18:17.980610 2866 master.cpp:477] Master terminating
>> I0415 03:18:18.082686 2866 master.cpp:283] Shutting down master
>> [ OK ] AllocatorTest/0.WhitelistSlave (166 ms)
>> [----------] 9 tests from AllocatorTest/0 (4735 ms total)
>>
>> [----------] 1 test from LoggingTest
>> [ RUN ] LoggingTest.Toggle
>> I0415 03:18:18.083878 2921 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.085011 2924 process.cpp:878] Socket closed while receiving
>> I0415 03:18:18.085589 2911 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.086385 2924 process.cpp:878] Socket closed while receiving
>> I0415 03:18:18.086869 2906 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.087815 2924 process.cpp:878] Socket closed while receiving
>> I0415 03:18:18.088340 2921 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.089082 2924 process.cpp:878] Socket closed while receiving
>> I0415 03:18:18.089656 2919 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.090312 2924 process.cpp:878] Socket closed while receiving
>> I0415 03:18:18.090739 2908 process.cpp:2931] Handling HTTP event for
>> process 'logging' with path: '/logging/toggle'
>> I0415 03:18:18.091506 2924 process.cpp:878] Socket closed while receiving
>> [ OK ] LoggingTest.Toggle (8 ms)
>> [----------] 1 test from LoggingTest (8 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 (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 (6 ms)
>> [----------] 3 tests from FsTest (6 ms total)
>>
>> [----------] 5 tests from ProcTest
>> [ RUN ] ProcTest.pids
>> [ OK ] ProcTest.pids (1 ms)
>> [ RUN ] ProcTest.children
>> [ OK ] ProcTest.children (15 ms)
>> [ RUN ] ProcTest.cpus
>> [ OK ] ProcTest.cpus (1 ms)
>> [ RUN ] ProcTest.SystemStatus
>> [ OK ] ProcTest.SystemStatus (0 ms)
>> [ RUN ] ProcTest.ProcessStatus
>> [ OK ] ProcTest.ProcessStatus (0 ms)
>> [----------] 5 tests from ProcTest (17 ms total)
>>
>> [----------] Global test environment tear-down
>> [==========] 166 tests from 36 test cases ran. (16508 ms total)
>> [ PASSED ] 165 tests.
>> [ FAILED ] 1 test, listed below:
>> [ FAILED ] MonitorTest.WatchUnwatch
>>
>> 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
>> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
>> make[2]: *** [check-am] Error 2
>> make[2]: Leaving directory
>> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
>> make[1]: *** [check] Error 2
>> make[1]: Leaving directory
>> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
>> make: *** [check-recursive] Error 1
>> Build step 'Execute shell' marked build as failure
>>
>
>
Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#304
Posted by Benjamin Mahler <be...@gmail.com>.
Odd.. I'm unable to reproduce this test failure, I ran with:
Linux: 35,000 iterations
OSX: 10,000 iterations
Both without any failures, I'm not sure what's special about this Jenkins
machine that would be inducing the test failure.
On Sun, Apr 14, 2013 at 8:18 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/304/changes
> >
>
> Changes:
>
> [bmahler] Replaced sleep() and usleep() calls with os::sleep().
>
> From: Jiang Yan Xu <ya...@jxu.me>
> Review: https://reviews.apache.org/r/10443
>
> [bmahler] Replaced usleep() calls in libprocess with os::sleep().
>
> From: Jiang Yan Xu <ya...@jxu.me>
> Review: https://reviews.apache.org/r/10241
>
> [bmahler] Changed os::sleep() to take a Duration instead of a double.
>
> From: Jiang Yan Xu <ya...@jxu.me>
> Review: https://reviews.apache.org/r/10231
>
> ------------------------------------------
> [...truncated 13521 lines...]
> I0415 03:18:15.913236 2917 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on
> slave 201304150318-453759884-55596-2866-0
> I0415 03:18:15.913281 2917 hierarchical_allocator_process.hpp:686]
> Offering cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0 to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.913363 2917 hierarchical_allocator_process.hpp:619]
> Performed allocation for slave 201304150318-453759884-55596-2866-0 in
> 134.48us
> I0415 03:18:15.913388 2903 master.hpp:309] Adding offer with resources
> cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:15.913475 2903 master.cpp:1327] Sending 1 offers to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.913573 2910 sched.cpp:282] Received 1 offers
> I0415 03:18:15.913787 2918 master.cpp:1534] Processing reply for offer
> 201304150318-453759884-55596-2866-0 on slave
> 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.913882 2918 master.hpp:289] Adding task with resources
> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
> I0415 03:18:15.913931 2918 master.cpp:1651] Launching task 0 of framework
> 201304150318-453759884-55596-2866-0000 with resources cpus=1; mem=256 on
> slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
> I0415 03:18:15.914011 2915 slave.cpp:587] Got assigned task 0 for
> framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.914033 2918 master.hpp:289] Adding task with resources
> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
> I0415 03:18:15.914106 2918 master.cpp:1651] Launching task 1 of framework
> 201304150318-453759884-55596-2866-0000 with resources cpus=1; mem=256 on
> slave 201304150318-453759884-55596-2866-0 (hemera.apache.org)
> I0415 03:18:15.914240 2918 master.hpp:318] Removing offer with resources
> cpus=3; mem=1024; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:15.914273 2916 hierarchical_allocator_process.hpp:471]
> Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512;
> ports=[31000-32000]; disk=57022 unused on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:15.914942 2915 paths.hpp:302] Created executor directory
> '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
> I0415 03:18:15.915096 2915 slave.cpp:587] Got assigned task 1 for
> framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.915132 2915 slave.cpp:671] Queuing task '1' for executor
> default of framework '201304150318-453759884-55596-2866-0000
> I0415 03:18:15.915251 2903 exec.cpp:170] Executor started at:
> executor(25)@140.211.11.27:55596 with pid 2866
> I0415 03:18:15.915268 2915 slave.cpp:436] Successfully attached file
> '/tmp/AllocatorTest_0_TaskFinished_rjnv8E/slaves/201304150318-453759884-55596-2866-0/frameworks/201304150318-453759884-55596-2866-0000/executors/default/runs/cfe67ea2-07ec-470a-ab13-4012a0218373'
> I0415 03:18:15.915354 2915 slave.cpp:1046] Got registration for executor
> 'default' of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.915442 2915 slave.cpp:1121] Flushing queued tasks for
> framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.915467 2920 exec.cpp:194] Executor registered on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:15.915563 2920 exec.cpp:258] Executor asked to run task '1'
> I0415 03:18:15.915665 2920 exec.cpp:258] Executor asked to run task '0'
> I0415 03:18:15.915822 2920 exec.cpp:382] Executor sending status update
> for task 1 in state TASK_RUNNING
> I0415 03:18:15.916580 2920 exec.cpp:382] Executor sending status update
> for task 0 in state TASK_RUNNING
> I0415 03:18:15.916599 2918 slave.cpp:1232] Handling status update
> TASK_RUNNING from task 1 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.916682 2918 slave.cpp:1280] Forwarding status update
> TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to the status update manager
> I0415 03:18:15.916764 2921 status_update_manager.cpp:254] Received status
> update TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.916803 2921 status_update_manager.cpp:403] Creating
> StatusUpdate stream for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.916858 2921 status_update_manager.hpp:314] Handling UPDATE
> for status update TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.916901 2921 status_update_manager.cpp:289] Forwarding
> status update TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to the master at
> master@140.211.11.27:55596
> I0415 03:18:15.916998 2918 master.cpp:1086] Status update from (728)@
> 140.211.11.27:55596: task 1 of framework
> 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
> I0415 03:18:15.917028 2922 slave.cpp:1342] Sending ACK for status update
> TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
> 140.211.11.27:55596
> I0415 03:18:15.917104 2914 sched.cpp:327] Received status update
> TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
> I0415 03:18:15.917176 2914 sched.cpp:360] Sending ACK for status update
> TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
> I0415 03:18:15.917233 2918 slave.cpp:979] Got acknowledgement of status
> update for task 1 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917300 2918 status_update_manager.cpp:314] Received status
> update acknowledgement for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917336 2918 status_update_manager.hpp:314] Handling ACK
> for status update TASK_RUNNING from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917394 2918 slave.cpp:1016] Status update manager
> successfully handled status update acknowledgement for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917529 2920 exec.cpp:289] Executor received ACK for status
> update of task 1 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917542 2915 slave.cpp:1232] Handling status update
> TASK_RUNNING from task 0 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917613 2915 slave.cpp:1280] Forwarding status update
> TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000 to the status update manager
> I0415 03:18:15.917690 2915 status_update_manager.cpp:254] Received status
> update TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917719 2915 status_update_manager.cpp:403] Creating
> StatusUpdate stream for task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917764 2915 status_update_manager.hpp:314] Handling UPDATE
> for status update TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.917842 2915 status_update_manager.cpp:289] Forwarding
> status update TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000 to the master at
> master@140.211.11.27:55596
> I0415 03:18:15.917922 2907 slave.cpp:1342] Sending ACK for status update
> TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
> 140.211.11.27:55596
> I0415 03:18:15.917939 2913 master.cpp:1086] Status update from (728)@
> 140.211.11.27:55596: task 0 of framework
> 201304150318-453759884-55596-2866-0000 is now in state TASK_RUNNING
> I0415 03:18:15.918010 2918 exec.cpp:289] Executor received ACK for status
> update of task 0 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.918071 2906 sched.cpp:327] Received status update
> TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
> I0415 03:18:15.918139 2906 sched.cpp:360] Sending ACK for status update
> TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
> I0415 03:18:15.918198 2906 slave.cpp:979] Got acknowledgement of status
> update for task 0 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.918287 2915 status_update_manager.cpp:314] Received status
> update acknowledgement for task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.918326 2915 status_update_manager.hpp:314] Handling ACK
> for status update TASK_RUNNING from task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:15.918392 2915 slave.cpp:1016] Status update manager
> successfully handled status update acknowledgement for task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.912462 2922 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=1; mem=512; ports=[31000-32000]; disk=57022 on
> slave 201304150318-453759884-55596-2866-0
> I0415 03:18:16.912578 2922 hierarchical_allocator_process.hpp:686]
> Offering cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0 to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.912758 2922 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 335.33us
> I0415 03:18:16.912857 2905 master.hpp:309] Adding offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:16.913120 2905 master.cpp:1327] Sending 1 offers to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.913333 2905 sched.cpp:282] Received 1 offers
> I0415 03:18:16.913681 2902 master.cpp:1534] Processing reply for offer
> 201304150318-453759884-55596-2866-1 on slave
> 201304150318-453759884-55596-2866-0 (hemera.apache.org) for framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.913811 2902 master.hpp:318] Removing offer with resources
> cpus=1; mem=512; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:16.913959 2901 exec.cpp:382] Executor sending status update
> for task 1 in state TASK_FINISHED
> I0415 03:18:16.914007 2914 hierarchical_allocator_process.hpp:471]
> Framework 201304150318-453759884-55596-2866-0000 left cpus=1; mem=512;
> ports=[31000-32000]; disk=57022 unused on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:16.915129 2901 slave.cpp:1232] Handling status update
> TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.915258 2901 slave.cpp:1280] Forwarding status update
> TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to the status update manager
> I0415 03:18:16.915374 2905 status_update_manager.cpp:254] Received status
> update TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.915477 2905 status_update_manager.hpp:314] Handling UPDATE
> for status update TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.915529 2905 status_update_manager.cpp:289] Forwarding
> status update TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to the master at
> master@140.211.11.27:55596
> I0415 03:18:16.915616 2913 master.cpp:1086] Status update from (728)@
> 140.211.11.27:55596: task 1 of framework
> 201304150318-453759884-55596-2866-0000 is now in state TASK_FINISHED
> I0415 03:18:16.915791 2913 master.hpp:300] Removing task with resources
> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
> I0415 03:18:16.915798 2923 slave.cpp:1342] Sending ACK for status update
> TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to executor executor(25)@
> 140.211.11.27:55596
> I0415 03:18:16.915802 2902 sched.cpp:327] Received status update
> TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000 from slave(80)@140.211.11.27:55596
> I0415 03:18:16.915993 2919 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768;
> ports=[31000-32000]; disk=57022) on slave
> 201304150318-453759884-55596-2866-0 from framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916048 2921 exec.cpp:289] Executor received ACK for status
> update of task 1 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916060 2902 sched.cpp:360] Sending ACK for status update
> TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000 to slave(80)@140.211.11.27:55596
> I0415 03:18:16.916378 2902 slave.cpp:979] Got acknowledgement of status
> update for task 1 of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916436 2923 status_update_manager.cpp:314] Received status
> update acknowledgement for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916502 2923 status_update_manager.hpp:314] Handling ACK
> for status update TASK_FINISHED from task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916667 2923 status_update_manager.cpp:434] Cleaning up
> status update stream for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:16.916731 2923 slave.cpp:1016] Status update manager
> successfully handled status update acknowledgement for task 1 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.094455 5607 exec.cpp:83] Committing suicide by killing the
> process group
> I0415 03:18:17.913380 2902 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=2; mem=768; ports=[31000-32000]; disk=57022 on
> slave 201304150318-453759884-55596-2866-0
> I0415 03:18:17.913491 2902 hierarchical_allocator_process.hpp:686]
> Offering cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0 to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.913616 2902 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 279.27us
> I0415 03:18:17.913749 2901 master.hpp:309] Adding offer with resources
> cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:17.913872 2901 master.cpp:1327] Sending 1 offers to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.914019 2909 sched.cpp:282] Received 1 offers
> I0415 03:18:17.914553 2912 sched.cpp:422] Stopping framework
> '201304150318-453759884-55596-2866-0000'
> I0415 03:18:17.914700 2903 master.cpp:774] Asked to unregister framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.914803 2903 master.hpp:300] Removing task with resources
> cpus=1; mem=256 on slave 201304150318-453759884-55596-2866-0
> I0415 03:18:17.914835 2906 slave.cpp:889] Asked to shut down framework
> 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
> I0415 03:18:17.914888 2903 master.hpp:318] Removing offer with resources
> cpus=2; mem=768; ports=[31000-32000]; disk=57022 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:17.914907 2917 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.914983 2906 slave.cpp:894] Shutting down framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915192 2917 hierarchical_allocator_process.hpp:544]
> Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[];
> disk=0) on slave 201304150318-453759884-55596-2866-0 from framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915209 2906 slave.cpp:1681] Shutting down executor
> 'default' of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915338 2917 hierarchical_allocator_process.hpp:544]
> Recovered cpus=2; mem=768; ports=[31000-32000]; disk=57022 (total
> allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=57022) on slave
> 201304150318-453759884-55596-2866-0 from framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915355 2918 exec.cpp:321] Executor asked to shutdown
> I0415 03:18:17.915359 2906 slave.cpp:386] Slave terminating
> I0415 03:18:17.915395 2923 status_update_manager.cpp:233] Closing status
> update streams for framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915529 2906 slave.cpp:889] Asked to shut down framework
> 201304150318-453759884-55596-2866-0000 by @0.0.0.0:0
> I0415 03:18:17.915591 2923 status_update_manager.cpp:434] Cleaning up
> status update stream for task 0 of framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915626 2906 slave.cpp:894] Shutting down framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915715 2906 slave.cpp:1681] Shutting down executor
> 'default' of framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915814 2906 status_update_manager.cpp:233] Closing status
> update streams for framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.915827 2912 master.cpp:537] Slave
> 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
> I0415 03:18:17.915956 2912 master.cpp:542] Removing disconnected slave
> 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not
> checkpointing!
> I0415 03:18:17.916239 2866 master.cpp:477] Master terminating
> I0415 03:18:17.916435 2866 master.cpp:283] Shutting down master
> [ OK ] AllocatorTest/0.TaskFinished (2006 ms)
> [ RUN ] AllocatorTest/0.WhitelistSlave
> I0415 03:18:17.918071 2916 master.cpp:309] Master started on
> 140.211.11.27:55596
> I0415 03:18:17.918131 2916 master.cpp:324] Master ID:
> 201304150318-453759884-55596-2866
> I0415 03:18:17.918467 2912 slave.cpp:203] Slave started on 81)@
> 140.211.11.27:55596
> I0415 03:18:17.918558 2908 hierarchical_allocator_process.hpp:236]
> Initializing hierarchical allocator process with master :
> master@140.211.11.27:55596
> I0415 03:18:17.918627 2908 hierarchical_allocator_process.hpp:437]
> Updated slave white list:
> I0415 03:18:17.918665 2908 hierarchical_allocator_process.hpp:439]
> dummy-slave
> I0415 03:18:17.918735 2916 master.cpp:603] Elected as master!
> I0415 03:18:17.918679 2912 slave.cpp:204] Slave resources: cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0415 03:18:17.918853 2910 hierarchical_allocator_process.hpp:632] No
> users to allocate resources!
> I0415 03:18:17.918828 2903 sched.cpp:182] New master at
> master@140.211.11.27:55596
> I0415 03:18:17.918905 2910 hierarchical_allocator_process.hpp:599]
> Performed allocation for 0 slaves in 61.30us
> I0415 03:18:17.919039 2917 master.cpp:646] Registering framework
> 201304150318-453759884-55596-2866-0000 at scheduler(73)@
> 140.211.11.27:55596
> I0415 03:18:17.919224 2912 slave.cpp:453] New master detected at
> master@140.211.11.27:55596
> I0415 03:18:17.919284 2910 hierarchical_allocator_process.hpp:268] Added
> framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.919239 2900 sched.cpp:217] Framework registered with
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.919296 2912 slave.cpp:377] Finished recovery
> I0415 03:18:17.919231 2917 master.cpp:633] Framework
> 201304150318-453759884-55596-2866-0000 (scheduler(73)@140.211.11.27:55596)
> already registered, resending acknowledgement
> I0415 03:18:17.919304 2914 status_update_manager.cpp:132] New master
> detected at master@140.211.11.27:55596
> I0415 03:18:17.919325 2910 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.919575 2917 sched.cpp:212] Ignoring framework registered
> message because the driver is already connected!
> I0415 03:18:17.919586 2910 hierarchical_allocator_process.hpp:599]
> Performed allocation for 0 slaves in 261.17us
> I0415 03:18:17.919595 2906 master.cpp:968] Attempting to register slave
> on hemera.apache.org at slave(81)@140.211.11.27:55596
> I0415 03:18:17.919742 2906 master.cpp:1224] Master now considering a
> slave at hemera.apache.org:55596 as active
> I0415 03:18:17.919791 2906 master.cpp:1862] Adding slave
> 201304150318-453759884-55596-2866-0 at hemera.apache.org with cpus=2;
> mem=1024; ports=[31000-32000]; disk=1024
> I0415 03:18:17.919870 2900 slave.cpp:487] Registered with master; given
> slave ID 201304150318-453759884-55596-2866-0
> I0415 03:18:17.919919 2906 master.cpp:957] Slave
> 201304150318-453759884-55596-2866-0 (hemera.apache.org) already
> registered, resending acknowledgement
> I0415 03:18:17.919980 2910 hierarchical_allocator_process.hpp:395] Added
> slave 201304150318-453759884-55596-2866-0 (hemera.apache.org) with
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024;
> ports=[31000-32000]; disk=1024 available)
> I0415 03:18:17.919999 2920 slave.cpp:487] Registered with master; given
> slave ID 201304150318-453759884-55596-2866-0
> I0415 03:18:17.920047 2910 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.920156 2910 hierarchical_allocator_process.hpp:619]
> Performed allocation for slave 201304150318-453759884-55596-2866-0 in
> 117.65us
> I0415 03:18:17.929066 2906 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.929118 2906 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 68.58us
> I0415 03:18:17.939112 2915 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.939164 2915 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 75.08us
> I0415 03:18:17.949193 2905 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.949237 2905 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 60.86us
> I0415 03:18:17.959239 2917 hierarchical_allocator_process.hpp:668] No
> resources available to allocate!
> I0415 03:18:17.959280 2917 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 47.76us
> I0415 03:18:17.959416 2917 hierarchical_allocator_process.hpp:437]
> Updated slave white list:
> I0415 03:18:17.959487 2917 hierarchical_allocator_process.hpp:439]
> dummy-slave
> I0415 03:18:17.959574 2917 hierarchical_allocator_process.hpp:439]
> hemera.apache.org
> I0415 03:18:17.969391 2922 hierarchical_allocator_process.hpp:660] Found
> available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on
> slave 201304150318-453759884-55596-2866-0
> I0415 03:18:17.969445 2922 hierarchical_allocator_process.hpp:686]
> Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201304150318-453759884-55596-2866-0 to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.969552 2922 hierarchical_allocator_process.hpp:599]
> Performed allocation for 1 slaves in 206.47us
> I0415 03:18:17.969589 2911 master.hpp:309] Adding offer with resources
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:17.969749 2911 master.cpp:1327] Sending 1 offers to framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.969852 2911 sched.cpp:282] Received 1 offers
> I0415 03:18:17.979460 2923 sched.cpp:422] Stopping framework
> '201304150318-453759884-55596-2866-0000'
> I0415 03:18:17.979574 2923 master.cpp:774] Asked to unregister framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.979686 2923 master.hpp:318] Removing offer with resources
> cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave
> 201304150318-453759884-55596-2866-0
> I0415 03:18:17.979750 2900 hierarchical_allocator_process.hpp:359]
> Deactivated framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.979802 2914 slave.cpp:889] Asked to shut down framework
> 201304150318-453759884-55596-2866-0000 by master@140.211.11.27:55596
> I0415 03:18:17.979941 2900 hierarchical_allocator_process.hpp:544]
> Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (total
> allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave
> 201304150318-453759884-55596-2866-0 from framework
> 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.979996 2907 status_update_manager.cpp:233] Closing status
> update streams for framework 201304150318-453759884-55596-2866-0000
> I0415 03:18:17.980291 2920 slave.cpp:386] Slave terminating
> I0415 03:18:17.980389 2911 master.cpp:537] Slave
> 201304150318-453759884-55596-2866-0(hemera.apache.org) disconnected
> I0415 03:18:17.980424 2911 master.cpp:542] Removing disconnected slave
> 201304150318-453759884-55596-2866-0(hemera.apache.org) because it is not
> checkpointing!
> I0415 03:18:17.980610 2866 master.cpp:477] Master terminating
> I0415 03:18:18.082686 2866 master.cpp:283] Shutting down master
> [ OK ] AllocatorTest/0.WhitelistSlave (166 ms)
> [----------] 9 tests from AllocatorTest/0 (4735 ms total)
>
> [----------] 1 test from LoggingTest
> [ RUN ] LoggingTest.Toggle
> I0415 03:18:18.083878 2921 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.085011 2924 process.cpp:878] Socket closed while receiving
> I0415 03:18:18.085589 2911 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.086385 2924 process.cpp:878] Socket closed while receiving
> I0415 03:18:18.086869 2906 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.087815 2924 process.cpp:878] Socket closed while receiving
> I0415 03:18:18.088340 2921 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.089082 2924 process.cpp:878] Socket closed while receiving
> I0415 03:18:18.089656 2919 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.090312 2924 process.cpp:878] Socket closed while receiving
> I0415 03:18:18.090739 2908 process.cpp:2931] Handling HTTP event for
> process 'logging' with path: '/logging/toggle'
> I0415 03:18:18.091506 2924 process.cpp:878] Socket closed while receiving
> [ OK ] LoggingTest.Toggle (8 ms)
> [----------] 1 test from LoggingTest (8 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 (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 (6 ms)
> [----------] 3 tests from FsTest (6 ms total)
>
> [----------] 5 tests from ProcTest
> [ RUN ] ProcTest.pids
> [ OK ] ProcTest.pids (1 ms)
> [ RUN ] ProcTest.children
> [ OK ] ProcTest.children (15 ms)
> [ RUN ] ProcTest.cpus
> [ OK ] ProcTest.cpus (1 ms)
> [ RUN ] ProcTest.SystemStatus
> [ OK ] ProcTest.SystemStatus (0 ms)
> [ RUN ] ProcTest.ProcessStatus
> [ OK ] ProcTest.ProcessStatus (0 ms)
> [----------] 5 tests from ProcTest (17 ms total)
>
> [----------] Global test environment tear-down
> [==========] 166 tests from 36 test cases ran. (16508 ms total)
> [ PASSED ] 165 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] MonitorTest.WatchUnwatch
>
> 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
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
> make[2]: *** [check-am] Error 2
> make[2]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
> make[1]: *** [check] Error 2
> make[1]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
> make: *** [check-recursive] Error 1
> Build step 'Execute shell' marked build as failure
>