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/30 05:27:32 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#317
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/317/changes>
Changes:
[bmahler] Removed linux/proc.{hpp,cpp} in favor of stout/proc.hpp.
Review: https://reviews.apache.org/r/10811
[bmahler] Added proc_tests.cpp to the libprocess third party tests.
Review: https://reviews.apache.org/r/10810
[bmahler] Added linux /proc utilities in stout.
Review: https://reviews.apache.org/r/10809
[bmahler] Fixed the master to shutdown deactivated slaves that send further
messages to the master.
Review: https://reviews.apache.org/r/10734
[bmahler] Removed the hostname:port slave map from the Master.
Review: https://reviews.apache.org/r/10534
[bmahler] Send NoMasterDetectedMessage on session timeout to non-contending
detectors. Added a disconnected slave map to the master to track
disconnected slaves, in order to disallow slave re-registration after
a network partition.
Review: https://reviews.apache.org/r/10172
[bmahler] Removed the unused SlavesManager and SlaveRegistrar code, to be added
later with MESOS-295.
Review: https://reviews.apache.org/r/10171
[bmahler] When sending NoMasterDetectedMessage, we need to clear the master
sequence number in order to ensure we still send
NewMasterDetectedMessage if the master is the same. Otherwise, we can
get stuck in a master-less state.
Review: https://reviews.apache.org/r/10161
[bmahler] Added an optional default value for Option::get().
------------------------------------------
[...truncated 8281 lines...]
I0430 03:27:31.804411 17794 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:43653
I0430 03:27:31.805448 17793 hierarchical_allocator_process.hpp:359] Deactivated framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.806442 17793 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 201304300327-1015726915-43653-17769-0 from framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.805868 17794 slave.cpp:443] Slave terminating
I0430 03:27:31.805490 17769 master.hpp:315] Removing offer 201304300327-1015726915-43653-17769-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.807976 17798 hierarchical_allocator_process.hpp:312] Removed framework 201304300327-1015726915-43653-17769-0000
[ OK ] GarbageCollectorIntegrationTest.ExitedExecutor (70 ms)
[ RUN ] GarbageCollectorIntegrationTest.DiskUsage
I0430 03:27:31.809545 17795 master.cpp:227] Master started on 67.195.138.60:43653
I0430 03:27:31.809604 17795 master.cpp:242] Master ID: 201304300327-1015726915-43653-17769
W0430 03:27:31.810061 17796 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0430 03:27:31.810075 17798 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:43653
I0430 03:27:31.810106 17793 slave.cpp:212] Slave started on 40)@67.195.138.60:43653
I0430 03:27:31.810312 17795 master.cpp:510] Elected as master!
I0430 03:27:31.810612 17794 sched.cpp:182] New master at master@67.195.138.60:43653
I0430 03:27:31.811794 17793 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:27:31.812944 17797 master.cpp:553] Registering framework 201304300327-1015726915-43653-17769-0000 at scheduler(31)@67.195.138.60:43653
I0430 03:27:31.814625 17797 sched.cpp:217] Framework registered with 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.814661 17793 slave.cpp:532] New master detected at master@67.195.138.60:43653
I0430 03:27:31.815454 17793 slave.cpp:547] Postponing registration until recovery is complete
I0430 03:27:31.815915 17793 slave.cpp:393] Finished recovery
I0430 03:27:31.815462 17797 status_update_manager.cpp:154] New master detected at master@67.195.138.60:43653
I0430 03:27:31.814707 17794 hierarchical_allocator_process.hpp:268] Added framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.816506 17798 master.cpp:875] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:43653
I0430 03:27:31.817875 17798 master.cpp:1828] Adding slave 201304300327-1015726915-43653-17769-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:27:31.818400 17795 slave.cpp:592] Registered with master master@67.195.138.60:43653; given slave ID 201304300327-1015726915-43653-17769-0
I0430 03:27:31.817390 17794 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0430 03:27:31.819350 17794 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.960296000000000ms
I0430 03:27:31.819869 17794 hierarchical_allocator_process.hpp:395] Added slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0430 03:27:31.820349 17794 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.820858 17794 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0 to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.821446 17794 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304300327-1015726915-43653-17769-0 in 1.105624000000000ms
I0430 03:27:31.821496 17795 master.hpp:305] Adding offer 201304300327-1015726915-43653-17769-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.822310 17795 master.cpp:1265] Sending 1 offers to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.822846 17800 sched.cpp:282] Received 1 offers
I0430 03:27:31.824142 17795 master.cpp:1498] Processing reply for offer 201304300327-1015726915-43653-17769-0 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.824827 17795 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.825323 17795 master.cpp:1617] Launching task 0 of framework 201304300327-1015726915-43653-17769-0000 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org)
I0430 03:27:31.825904 17800 slave.cpp:721] Got assigned task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.825919 17794 hierarchical_allocator_process.hpp:471] Framework 201304300327-1015726915-43653-17769-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.825932 17795 master.hpp:315] Removing offer 201304300327-1015726915-43653-17769-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.826522 17800 slave.cpp:819] Launching task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.826836 17794 hierarchical_allocator_process.hpp:497] Framework 201304300327-1015726915-43653-17769-0000 filtered slave 201304300327-1015726915-43653-17769-0 for 5.000000000000000secs
I0430 03:27:31.828910 17800 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/5588dab3-870d-4a0b-92a9-454cac5d92e3'
I0430 03:27:31.829370 17800 slave.cpp:930] Queuing task '0' for executor default of framework '201304300327-1015726915-43653-17769-0000
I0430 03:27:31.829675 17800 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/5588dab3-870d-4a0b-92a9-454cac5d92e3'
I0430 03:27:31.829488 17794 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:43653 with pid 17769
I0430 03:27:31.831423 17794 slave.cpp:1382] Got registration for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.831907 17794 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.831935 17797 exec.cpp:194] Executor registered on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.832952 17797 exec.cpp:258] Executor asked to run task '0'
I0430 03:27:31.834956 17797 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.835021 17797 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.835423 17797 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000 with checkpoint=false
I0430 03:27:31.835844 17797 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.836427 17797 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to master@67.195.138.60:43653
I0430 03:27:31.837828 17799 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.837832 17796 master.cpp:1048] Status update from slave(40)@67.195.138.60:43653: task 0 of framework 201304300327-1015726915-43653-17769-0000 is now in state TASK_RUNNING
I0430 03:27:31.838187 17799 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to executor(16)@67.195.138.60:43653
I0430 03:27:31.839372 17796 exec.cpp:290] Executor received status update acknowledgement �Y%�
�H��`?vY�9� for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.838778 17797 sched.cpp:327] Received status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000 from slave(40)@67.195.138.60:43653
I0430 03:27:31.840493 17797 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: fa5925cd-0db7-489d-9860-3f7659b639e4) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to slave(40)@67.195.138.60:43653
I0430 03:27:31.840698 17799 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0430 03:27:31.841006 17798 status_update_manager.cpp:359] Received status update acknowledgement fa5925cd-0db7-489d-9860-3f7659b639e4 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.841953 17801 process.cpp:870] Socket closed while receiving
I0430 03:27:31.841977 17798 slave.cpp:1332] Status update manager successfully handled status update acknowledgement fa5925cd-0db7-489d-9860-3f7659b639e4 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.843792 17797 slave.cpp:2031] Executor 'default' of framework 201304300327-1015726915-43653-17769-0000 has exited with status '0'
I0430 03:27:31.845551 17797 slave.cpp:1726] Handling status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.845708 17796 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000 with checkpoint=false
I0430 03:27:31.845953 17796 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to master@67.195.138.60:43653
I0430 03:27:31.845767 17797 master.cpp:1142] Executor default of framework 201304300327-1015726915-43653-17769-0000 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) exited with status 0
I0430 03:27:31.846979 17797 master.cpp:1048] Status update from slave(40)@67.195.138.60:43653: task 0 of framework 201304300327-1015726915-43653-17769-0000 is now in state TASK_LOST
I0430 03:27:31.847657 17797 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.847674 17799 sched.cpp:327] Received status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000 from slave(40)@67.195.138.60:43653
I0430 03:27:31.848597 17799 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to slave(40)@67.195.138.60:43653
I0430 03:27:31.848175 17794 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304300327-1015726915-43653-17769-0 from framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.846530 17793 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.850611 17793 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: 165f96a9-ae4b-48b0-8edb-0d6913a86364) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to executor(16)@67.195.138.60:43653
I0430 03:27:31.851840 17793 status_update_manager.cpp:359] Received status update acknowledgement 165f96a9-ae4b-48b0-8edb-0d6913a86364 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.852226 17793 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.852752 17793 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 165f96a9-ae4b-48b0-8edb-0d6913a86364 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.853168 17793 slave.cpp:2166] Cleaning up executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.853899 17797 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/5588dab3-870d-4a0b-92a9-454cac5d92e3' for removal
I0430 03:27:31.854346 17797 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default' for removal
I0430 03:27:31.853904 17793 slave.cpp:2230] Cleaning up framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.855300 17797 status_update_manager.cpp:251] Closing status update streams for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.855352 17793 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000' for removal
I0430 03:27:31.864241 17799 slave.cpp:2482] Current disk usage 100.00%. Max allowed age: 0.000000000000000ns
I0430 03:27:31.864367 17798 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0430 03:27:31.864665 17798 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/5588dab3-870d-4a0b-92a9-454cac5d92e3
I0430 03:27:31.865088 17798 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/5588dab3-870d-4a0b-92a9-454cac5d92e3'
I0430 03:27:31.866336 17798 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default
I0430 03:27:31.866896 17798 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default'
I0430 03:27:31.867285 17798 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000
I0430 03:27:31.868443 17798 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_eRTihm/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000'
I0430 03:27:31.874577 17799 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0430 03:27:31.875398 17801 process.cpp:870] Socket closed while receiving
I0430 03:27:31.875840 17769 master.cpp:384] Master terminating
I0430 03:27:31.875849 17794 sched.cpp:422] Stopping framework '201304300327-1015726915-43653-17769-0000'
I0430 03:27:31.875908 17769 master.cpp:206] Shutting down master
I0430 03:27:31.876864 17793 hierarchical_allocator_process.hpp:359] Deactivated framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.875941 17795 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:43653
I0430 03:27:31.877789 17795 slave.cpp:443] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (69 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0430 03:27:31.879169 17799 master.cpp:227] Master started on 67.195.138.60:43653
I0430 03:27:31.879688 17799 master.cpp:242] Master ID: 201304300327-1015726915-43653-17769
I0430 03:27:31.880199 17794 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:43653
W0430 03:27:31.880225 17797 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0430 03:27:31.880393 17799 master.cpp:510] Elected as master!
I0430 03:27:31.879744 17800 slave.cpp:212] Slave started on 41)@67.195.138.60:43653
I0430 03:27:31.882377 17800 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:27:31.883330 17800 slave.cpp:532] New master detected at master@67.195.138.60:43653
I0430 03:27:31.883369 17800 slave.cpp:547] Postponing registration until recovery is complete
I0430 03:27:31.884600 17800 slave.cpp:393] Finished recovery
I0430 03:27:31.883394 17793 status_update_manager.cpp:154] New master detected at master@67.195.138.60:43653
I0430 03:27:31.885145 17798 master.cpp:875] Attempting to register slave on janus.apache.org at slave(41)@67.195.138.60:43653
I0430 03:27:31.886003 17798 master.cpp:1828] Adding slave 201304300327-1015726915-43653-17769-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:27:31.886569 17798 slave.cpp:592] Registered with master master@67.195.138.60:43653; given slave ID 201304300327-1015726915-43653-17769-0
I0430 03:27:31.886672 17796 hierarchical_allocator_process.hpp:395] Added slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=1024 (and cpus=2; mem=1024; ports=[31000-32000]; disk=1024 available)
I0430 03:27:31.887799 17796 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0430 03:27:31.888980 17796 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304300327-1015726915-43653-17769-0 in 1.182483000000000ms
I0430 03:27:31.887284 17794 sched.cpp:182] New master at master@67.195.138.60:43653
I0430 03:27:31.891206 17794 master.cpp:553] Registering framework 201304300327-1015726915-43653-17769-0000 at scheduler(32)@67.195.138.60:43653
I0430 03:27:31.891543 17798 sched.cpp:217] Framework registered with 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.891585 17794 hierarchical_allocator_process.hpp:268] Added framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.892490 17794 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.892995 17794 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0 to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.893617 17794 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.140608000000000ms
I0430 03:27:31.893697 17795 master.hpp:305] Adding offer 201304300327-1015726915-43653-17769-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.894548 17795 master.cpp:1265] Sending 1 offers to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.895299 17799 sched.cpp:282] Received 1 offers
I0430 03:27:31.895975 17795 master.cpp:1498] Processing reply for offer 201304300327-1015726915-43653-17769-0 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.896334 17795 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.896778 17795 master.cpp:1617] Launching task 0 of framework 201304300327-1015726915-43653-17769-0000 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org)
I0430 03:27:31.897387 17799 slave.cpp:721] Got assigned task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.898013 17799 slave.cpp:819] Launching task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.897443 17795 master.hpp:315] Removing offer 201304300327-1015726915-43653-17769-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.897433 17793 hierarchical_allocator_process.hpp:471] Framework 201304300327-1015726915-43653-17769-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.900037 17793 hierarchical_allocator_process.hpp:497] Framework 201304300327-1015726915-43653-17769-0000 filtered slave 201304300327-1015726915-43653-17769-0 for 5.000000000000000secs
I0430 03:27:31.899467 17799 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/62b675fd-addd-4de6-a166-cb304f9c48cc'
I0430 03:27:31.901676 17799 slave.cpp:930] Queuing task '0' for executor default of framework '201304300327-1015726915-43653-17769-0000
I0430 03:27:31.902138 17799 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/62b675fd-addd-4de6-a166-cb304f9c48cc'
I0430 03:27:31.901840 17795 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:43653 with pid 17769
I0430 03:27:31.903106 17795 slave.cpp:1382] Got registration for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.903851 17795 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.903880 17796 exec.cpp:194] Executor registered on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.905359 17796 exec.cpp:258] Executor asked to run task '0'
I0430 03:27:31.906966 17796 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.907040 17796 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.907837 17796 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000 with checkpoint=false
I0430 03:27:31.908188 17796 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.908694 17796 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to master@67.195.138.60:43653
I0430 03:27:31.909219 17794 master.cpp:1048] Status update from slave(41)@67.195.138.60:43653: task 0 of framework 201304300327-1015726915-43653-17769-0000 is now in state TASK_RUNNING
I0430 03:27:31.909225 17796 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.910737 17796 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to executor(17)@67.195.138.60:43653
I0430 03:27:31.910295 17798 sched.cpp:327] Received status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000 from slave(41)@67.195.138.60:43653
I0430 03:27:31.911223 17794 exec.cpp:290] Executor received status update acknowledgement ~��aB�B%� )�Ej
� for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.911855 17798 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 7ec9ca61-42ab-4225-811d-2987456a0a9d) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to slave(41)@67.195.138.60:43653
I0430 03:27:31.912598 17800 slave.cpp:2031] Executor 'default' of framework 201304300327-1015726915-43653-17769-0000 has exited with status '0'
I0430 03:27:31.914283 17800 slave.cpp:1726] Handling status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.914458 17793 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000 with checkpoint=false
I0430 03:27:31.914846 17793 status_update_manager.cpp:359] Received status update acknowledgement 7ec9ca61-42ab-4225-811d-2987456a0a9d for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.915271 17793 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to master@67.195.138.60:43653
I0430 03:27:31.914871 17799 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.916894 17799 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to executor(17)@67.195.138.60:43653
I0430 03:27:31.917397 17799 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 7ec9ca61-42ab-4225-811d-2987456a0a9d for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.914496 17794 master.cpp:1142] Executor default of framework 201304300327-1015726915-43653-17769-0000 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) exited with status 0
I0430 03:27:31.919529 17794 master.cpp:1048] Status update from slave(41)@67.195.138.60:43653: task 0 of framework 201304300327-1015726915-43653-17769-0000 is now in state TASK_LOST
I0430 03:27:31.919932 17794 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.919951 17793 sched.cpp:327] Received status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000 from slave(41)@67.195.138.60:43653
I0430 03:27:31.921833 17793 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 35867ad5-0f77-40af-a2e8-373b9535d27a) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to slave(41)@67.195.138.60:43653
I0430 03:27:31.921376 17794 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304300327-1015726915-43653-17769-0 from framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.922457 17793 status_update_manager.cpp:359] Received status update acknowledgement 35867ad5-0f77-40af-a2e8-373b9535d27a for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.923370 17793 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.923887 17793 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 35867ad5-0f77-40af-a2e8-373b9535d27a for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.924310 17793 slave.cpp:2166] Cleaning up executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.924983 17797 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/62b675fd-addd-4de6-a166-cb304f9c48cc' for removal
I0430 03:27:31.925004 17793 slave.cpp:2230] Cleaning up framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.925856 17794 status_update_manager.cpp:251] Closing status update streams for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.925407 17797 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default' for removal
I0430 03:27:31.927620 17797 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000' for removal
I0430 03:27:31.927817 17796 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.928670 17796 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0 to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.929262 17796 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.484743000000000ms
I0430 03:27:31.929308 17797 master.hpp:305] Adding offer 201304300327-1015726915-43653-17769-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.930142 17797 master.cpp:1265] Sending 1 offers to framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.930855 17793 sched.cpp:282] Received 1 offers
I0430 03:27:31.931398 17797 master.cpp:1498] Processing reply for offer 201304300327-1015726915-43653-17769-1 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org) for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.931797 17797 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.933349 17797 master.cpp:1617] Launching task 0 of framework 201304300327-1015726915-43653-17769-0000 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0 (janus.apache.org)
I0430 03:27:31.933823 17793 slave.cpp:721] Got assigned task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.933883 17797 master.hpp:315] Removing offer 201304300327-1015726915-43653-17769-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.933904 17800 hierarchical_allocator_process.hpp:471] Framework 201304300327-1015726915-43653-17769-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.935915 17800 hierarchical_allocator_process.hpp:497] Framework 201304300327-1015726915-43653-17769-0000 filtered slave 201304300327-1015726915-43653-17769-0 for 5.000000000000000secs
I0430 03:27:31.935343 17793 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000' for removal
I0430 03:27:31.937273 17793 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default' for removal
I0430 03:27:31.937631 17797 slave.cpp:819] Launching task 0 for framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.939035 17797 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/104cae57-404c-4a28-8995-af4b36d5dac0'
I0430 03:27:31.939218 17797 slave.cpp:930] Queuing task '0' for executor default of framework '201304300327-1015726915-43653-17769-0000
I0430 03:27:31.939462 17794 exec.cpp:170] Executor started at: executor(18)@67.195.138.60:43653 with pid 17769
I0430 03:27:31.939498 17797 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_Afgfsl/slaves/201304300327-1015726915-43653-17769-0/frameworks/201304300327-1015726915-43653-17769-0000/executors/default/runs/104cae57-404c-4a28-8995-af4b36d5dac0'
I0430 03:27:31.940517 17797 slave.cpp:1382] Got registration for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.941679 17797 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.941730 17796 exec.cpp:194] Executor registered on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.943281 17796 exec.cpp:258] Executor asked to run task '0'
I0430 03:27:31.944651 17796 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.944722 17796 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.945359 17796 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000 with checkpoint=false
I0430 03:27:31.946518 17796 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.946959 17796 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to master@67.195.138.60:43653
I0430 03:27:31.947742 17795 master.cpp:1048] Status update from slave(41)@67.195.138.60:43653: task 0 of framework 201304300327-1015726915-43653-17769-0000 is now in state TASK_RUNNING
I0430 03:27:31.947778 17793 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.949143 17793 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to executor(18)@67.195.138.60:43653
I0430 03:27:31.949655 17796 exec.cpp:290] Executor received status update acknowledgement ��d,nJa�11@���� for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.948698 17795 sched.cpp:327] Received status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000 from slave(41)@67.195.138.60:43653
I0430 03:27:31.950706 17795 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 959c642c-156e-4a61-a331-3140bdc3c7f2) for task 0 of framework 201304300327-1015726915-43653-17769-0000 to slave(41)@67.195.138.60:43653
I0430 03:27:31.951832 17793 status_update_manager.cpp:359] Received status update acknowledgement 959c642c-156e-4a61-a331-3140bdc3c7f2 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.952268 17793 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 959c642c-156e-4a61-a331-3140bdc3c7f2 for task 0 of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.957924 17769 master.cpp:384] Master terminating
I0430 03:27:31.958003 17769 master.cpp:206] Shutting down master
I0430 03:27:31.957947 17798 sched.cpp:422] Stopping framework '201304300327-1015726915-43653-17769-0000'
I0430 03:27:31.958014 17795 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:43653
I0430 03:27:31.959214 17795 slave.cpp:1101] Asked to shut down framework 201304300327-1015726915-43653-17769-0000 by master@67.195.138.60:43653
I0430 03:27:31.958385 17769 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300327-1015726915-43653-17769-0
I0430 03:27:31.958372 17797 hierarchical_allocator_process.hpp:359] Deactivated framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.959710 17795 slave.cpp:1126] Shutting down framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.961227 17795 slave.cpp:2299] Shutting down executor 'default' of framework 201304300327-1015726915-43653-17769-0000
I0430 03:27:31.961963 17795 slave.cpp:443] Slave terminating
I0430 03:27:31.962452 17795 slave.cpp:1101] Asked to shut down framework 201304300327-1015726915-43653-17769-0000 by @0.0.0.0:0
W0430 03:27:31.962929 17795 slave.cpp:1122] Ignoring shutdown framework 201304300327-1015726915-43653-17769-0000 because it is terminating
I0430 03:27:31.962025 17796 exec.cpp:323] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
/bin/bash: line 5: 17769 Aborted ${dir}$tst
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
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#318
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/318/changes>