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/27 07:29:48 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #315
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/315/changes>
Changes:
[vinodkone] Fixed the slave to do disk usage calculations on the filesystem on
which the slave work directory is mounted.
Review: https://reviews.apache.org/r/10661
[vinodkone] Fixed master to consolidate tasks upon slave re-registration.
Review: https://reviews.apache.org/r/10724
[vinodkone] Cleaned up logging in slave, status update manager and executor driver.
Review: https://reviews.apache.org/r/10708
------------------------------------------
[...truncated 8795 lines...]
I0427 05:29:48.326751 3958 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:47553
I0427 05:29:48.328047 3958 slave.cpp:443] Slave terminating
I0427 05:29:48.327203 3952 hierarchical_allocator_process.hpp:359] Deactivated framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.326030 3956 sched.cpp:422] Stopping framework '201304270529-1015726915-47553-3718-0000'
[ OK ] GarbageCollectorIntegrationTest.ExitedExecutor (70 ms)
[ RUN ] GarbageCollectorIntegrationTest.DiskUsage
I0427 05:29:48.330303 3952 master.cpp:311] Master started on 67.195.138.60:47553
I0427 05:29:48.330414 3952 master.cpp:326] Master ID: 201304270529-1015726915-47553-3718
I0427 05:29:48.330782 3959 slave.cpp:212] Slave started on 40)@67.195.138.60:47553
I0427 05:29:48.331260 3953 sched.cpp:182] New master at master@67.195.138.60:47553
W0427 05:29:48.331290 3955 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0427 05:29:48.331341 3958 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:47553
I0427 05:29:48.331488 3952 master.cpp:605] Elected as master!
I0427 05:29:48.334235 3952 master.cpp:648] Registering framework 201304270529-1015726915-47553-3718-0000 at scheduler(31)@67.195.138.60:47553
I0427 05:29:48.331634 3959 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0427 05:29:48.335474 3952 hierarchical_allocator_process.hpp:268] Added framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.335486 3957 sched.cpp:217] Framework registered with 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.336483 3959 slave.cpp:532] New master detected at master@67.195.138.60:47553
I0427 05:29:48.336508 3952 hierarchical_allocator_process.hpp:668] No resources available to allocate!
I0427 05:29:48.337949 3952 hierarchical_allocator_process.hpp:599] Performed allocation for 0 slaves in 1.442402000000000ms
I0427 05:29:48.337527 3958 status_update_manager.cpp:154] New master detected at master@67.195.138.60:47553
I0427 05:29:48.337493 3959 slave.cpp:547] Postponing registration until recovery is complete
I0427 05:29:48.339664 3959 slave.cpp:393] Finished recovery
I0427 05:29:48.340198 3959 master.cpp:970] Attempting to register slave on janus.apache.org at slave(40)@67.195.138.60:47553
I0427 05:29:48.340600 3959 master.cpp:1272] Master now considering a slave at janus.apache.org:47553 as active
I0427 05:29:48.341070 3959 master.cpp:1938] Adding slave 201304270529-1015726915-47553-3718-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0427 05:29:48.341608 3959 slave.cpp:592] Registered with master master@67.195.138.60:47553; given slave ID 201304270529-1015726915-47553-3718-0
I0427 05:29:48.341682 3956 hierarchical_allocator_process.hpp:395] Added slave 201304270529-1015726915-47553-3718-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)
I0427 05:29:48.342587 3956 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.343047 3956 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0 to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.344496 3956 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304270529-1015726915-47553-3718-0 in 1.922335000000000ms
I0427 05:29:48.344524 3959 master.hpp:311] Adding offer 201304270529-1015726915-47553-3718-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.345417 3959 master.cpp:1375] Sending 1 offers to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.345916 3957 sched.cpp:282] Received 1 offers
I0427 05:29:48.346488 3959 master.cpp:1608] Processing reply for offer 201304270529-1015726915-47553-3718-0 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org) for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.346884 3959 master.hpp:289] Adding task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.347390 3959 master.cpp:1727] Launching task 0 of framework 201304270529-1015726915-47553-3718-0000 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org)
I0427 05:29:48.347911 3957 slave.cpp:721] Got assigned task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.347949 3952 hierarchical_allocator_process.hpp:471] Framework 201304270529-1015726915-47553-3718-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.349160 3952 hierarchical_allocator_process.hpp:497] Framework 201304270529-1015726915-47553-3718-0000 filtered slave 201304270529-1015726915-47553-3718-0 for 5.000000000000000secs
I0427 05:29:48.348549 3957 slave.cpp:819] Launching task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.347965 3959 master.hpp:321] Removing offer 201304270529-1015726915-47553-3718-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.352440 3957 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/693c061b-b2bb-4717-b2aa-9b7f7bfc8ad4'
I0427 05:29:48.352628 3957 slave.cpp:930] Queuing task '0' for executor default of framework '201304270529-1015726915-47553-3718-0000
I0427 05:29:48.353600 3957 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/693c061b-b2bb-4717-b2aa-9b7f7bfc8ad4'
I0427 05:29:48.352783 3959 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:47553 with pid 3718
I0427 05:29:48.354542 3959 slave.cpp:1382] Got registration for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.355049 3959 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.355069 3952 exec.cpp:194] Executor registered on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.356883 3952 exec.cpp:258] Executor asked to run task '0'
I0427 05:29:48.358541 3952 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.358603 3952 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.359011 3955 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000 with checkpoint=false
I0427 05:29:48.359417 3955 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.359959 3955 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to master@67.195.138.60:47553
I0427 05:29:48.360445 3953 master.cpp:1134] Status update from (147)@67.195.138.60:47553: task 0 of framework 201304270529-1015726915-47553-3718-0000 is now in state TASK_RUNNING
I0427 05:29:48.360503 3955 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.361362 3955 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to executor(16)@67.195.138.60:47553
I0427 05:29:48.360963 3952 sched.cpp:327] Received status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000 from slave(40)@67.195.138.60:47553
I0427 05:29:48.361888 3953 exec.cpp:290] Executor received status update acknowledgement �R|�F�@X���e�(C for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.362433 3952 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: ca527cff-46d4-4058-b8da-d565f6284306) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to slave(40)@67.195.138.60:47553
I0427 05:29:48.362656 3959 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0427 05:29:48.363471 3955 status_update_manager.cpp:359] Received status update acknowledgement ca527cff-46d4-4058-b8da-d565f6284306 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.364508 3960 process.cpp:870] Socket closed while receiving
I0427 05:29:48.364686 3955 slave.cpp:1332] Status update manager successfully handled status update acknowledgement ca527cff-46d4-4058-b8da-d565f6284306 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.365829 3955 slave.cpp:2031] Executor 'default' of framework 201304270529-1015726915-47553-3718-0000 has exited with status '0'
I0427 05:29:48.367409 3955 slave.cpp:1726] Handling status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.367652 3958 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000 with checkpoint=false
I0427 05:29:48.367914 3952 master.cpp:1236] Executor default of framework 201304270529-1015726915-47553-3718-0000 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org) exited with status 0
I0427 05:29:48.368437 3958 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to master@67.195.138.60:47553
I0427 05:29:48.369369 3958 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.369372 3957 master.cpp:1134] Status update from (147)@67.195.138.60:47553: task 0 of framework 201304270529-1015726915-47553-3718-0000 is now in state TASK_LOST
I0427 05:29:48.369758 3958 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to executor(16)@67.195.138.60:47553
I0427 05:29:48.370329 3957 master.hpp:301] Removing task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.370340 3954 sched.cpp:327] Received status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000 from slave(40)@67.195.138.60:47553
I0427 05:29:48.371395 3955 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304270529-1015726915-47553-3718-0 from framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.371810 3954 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 639c1330-be0e-4211-82fe-eab5d326f569) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to slave(40)@67.195.138.60:47553
I0427 05:29:48.373071 3954 status_update_manager.cpp:359] Received status update acknowledgement 639c1330-be0e-4211-82fe-eab5d326f569 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.373451 3954 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.373970 3954 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 639c1330-be0e-4211-82fe-eab5d326f569 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.374446 3954 slave.cpp:2166] Cleaning up executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.375108 3959 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/693c061b-b2bb-4717-b2aa-9b7f7bfc8ad4' for removal
I0427 05:29:48.375489 3959 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default' for removal
I0427 05:29:48.375154 3954 slave.cpp:2230] Cleaning up framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.377148 3959 status_update_manager.cpp:251] Closing status update streams for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.377192 3953 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000' for removal
I0427 05:29:48.385491 3957 slave.cpp:2482] Current disk usage 100.00%. Max allowed age: 0.000000000000000ns
I0427 05:29:48.385591 3952 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0427 05:29:48.385938 3952 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/693c061b-b2bb-4717-b2aa-9b7f7bfc8ad4
I0427 05:29:48.386874 3952 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/693c061b-b2bb-4717-b2aa-9b7f7bfc8ad4'
I0427 05:29:48.387315 3952 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default
I0427 05:29:48.387814 3952 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default'
I0427 05:29:48.388223 3952 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000
I0427 05:29:48.388782 3952 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_kngotR/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000'
I0427 05:29:48.395747 3955 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0427 05:29:48.396363 3960 process.cpp:870] Socket closed while receiving
I0427 05:29:48.397335 3952 sched.cpp:422] Stopping framework '201304270529-1015726915-47553-3718-0000'
I0427 05:29:48.397342 3718 master.cpp:479] Master terminating
I0427 05:29:48.397790 3718 master.cpp:285] Shutting down master
I0427 05:29:48.398305 3959 hierarchical_allocator_process.hpp:359] Deactivated framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.397810 3956 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:47553
I0427 05:29:48.399226 3956 slave.cpp:443] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (71 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0427 05:29:48.401166 3954 master.cpp:311] Master started on 67.195.138.60:47553
I0427 05:29:48.401206 3956 slave.cpp:212] Slave started on 41)@67.195.138.60:47553
I0427 05:29:48.401751 3956 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0427 05:29:48.401247 3954 master.cpp:326] Master ID: 201304270529-1015726915-47553-3718
I0427 05:29:48.402722 3956 slave.cpp:532] New master detected at master@67.195.138.60:47553
I0427 05:29:48.403167 3956 slave.cpp:547] Postponing registration until recovery is complete
I0427 05:29:48.402993 3958 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:47553
I0427 05:29:48.403199 3953 status_update_manager.cpp:154] New master detected at master@67.195.138.60:47553
W0427 05:29:48.402971 3952 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0427 05:29:48.403658 3956 slave.cpp:393] Finished recovery
I0427 05:29:48.403801 3954 master.cpp:605] Elected as master!
I0427 05:29:48.406949 3954 master.cpp:970] Attempting to register slave on janus.apache.org at slave(41)@67.195.138.60:47553
I0427 05:29:48.407362 3954 master.cpp:1272] Master now considering a slave at janus.apache.org:47553 as active
I0427 05:29:48.407866 3954 master.cpp:1938] Adding slave 201304270529-1015726915-47553-3718-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0427 05:29:48.408471 3959 slave.cpp:592] Registered with master master@67.195.138.60:47553; given slave ID 201304270529-1015726915-47553-3718-0
I0427 05:29:48.408491 3954 hierarchical_allocator_process.hpp:395] Added slave 201304270529-1015726915-47553-3718-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)
I0427 05:29:48.408993 3958 sched.cpp:182] New master at master@67.195.138.60:47553
I0427 05:29:48.409359 3954 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0427 05:29:48.410810 3957 master.cpp:648] Registering framework 201304270529-1015726915-47553-3718-0000 at scheduler(32)@67.195.138.60:47553
I0427 05:29:48.411142 3954 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304270529-1015726915-47553-3718-0 in 1.782723000000000ms
I0427 05:29:48.412163 3954 hierarchical_allocator_process.hpp:268] Added framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.412678 3954 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.413152 3954 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0 to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.413743 3954 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.077742000000000ms
I0427 05:29:48.411691 3958 sched.cpp:217] Framework registered with 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.413806 3952 master.hpp:311] Adding offer 201304270529-1015726915-47553-3718-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.415395 3952 master.cpp:1375] Sending 1 offers to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.415884 3959 sched.cpp:282] Received 1 offers
I0427 05:29:48.416524 3954 master.cpp:1608] Processing reply for offer 201304270529-1015726915-47553-3718-0 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org) for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.416883 3954 master.hpp:289] Adding task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.417341 3954 master.cpp:1727] Launching task 0 of framework 201304270529-1015726915-47553-3718-0000 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org)
I0427 05:29:48.417879 3953 slave.cpp:721] Got assigned task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.417922 3959 hierarchical_allocator_process.hpp:471] Framework 201304270529-1015726915-47553-3718-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.417920 3954 master.hpp:321] Removing offer 201304270529-1015726915-47553-3718-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.419327 3953 slave.cpp:819] Launching task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.419749 3959 hierarchical_allocator_process.hpp:497] Framework 201304270529-1015726915-47553-3718-0000 filtered slave 201304270529-1015726915-47553-3718-0 for 5.000000000000000secs
I0427 05:29:48.422103 3953 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/e7084204-d206-4679-bdb8-0409280c1f0e'
I0427 05:29:48.422262 3953 slave.cpp:930] Queuing task '0' for executor default of framework '201304270529-1015726915-47553-3718-0000
I0427 05:29:48.422400 3952 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:47553 with pid 3718
I0427 05:29:48.422613 3953 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/e7084204-d206-4679-bdb8-0409280c1f0e'
I0427 05:29:48.423655 3953 slave.cpp:1382] Got registration for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.425041 3953 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.425076 3955 exec.cpp:194] Executor registered on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.426100 3955 exec.cpp:258] Executor asked to run task '0'
I0427 05:29:48.428163 3955 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.428241 3955 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.428640 3955 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000 with checkpoint=false
I0427 05:29:48.429036 3955 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.429554 3955 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to master@67.195.138.60:47553
I0427 05:29:48.430076 3956 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.430078 3954 master.cpp:1134] Status update from (151)@67.195.138.60:47553: task 0 of framework 201304270529-1015726915-47553-3718-0000 is now in state TASK_RUNNING
I0427 05:29:48.430469 3956 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to executor(17)@67.195.138.60:47553
I0427 05:29:48.432607 3954 exec.cpp:290] Executor received status update acknowledgement ��eH�L��l�B�-�2 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.431584 3952 sched.cpp:327] Received status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000 from slave(41)@67.195.138.60:47553
I0427 05:29:48.433673 3952 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 07acdc65-48d1-4cef-af6c-fc42802db832) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to slave(41)@67.195.138.60:47553
I0427 05:29:48.434573 3955 status_update_manager.cpp:359] Received status update acknowledgement 07acdc65-48d1-4cef-af6c-fc42802db832 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.434584 3953 slave.cpp:2031] Executor 'default' of framework 201304270529-1015726915-47553-3718-0000 has exited with status '0'
I0427 05:29:48.437227 3953 slave.cpp:1726] Handling status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.437460 3957 master.cpp:1236] Executor default of framework 201304270529-1015726915-47553-3718-0000 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org) exited with status 0
I0427 05:29:48.437471 3953 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 07acdc65-48d1-4cef-af6c-fc42802db832 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.437489 3955 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000 with checkpoint=false
I0427 05:29:48.438570 3955 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to master@67.195.138.60:47553
I0427 05:29:48.439332 3958 master.cpp:1134] Status update from (151)@67.195.138.60:47553: task 0 of framework 201304270529-1015726915-47553-3718-0000 is now in state TASK_LOST
I0427 05:29:48.439833 3958 master.hpp:301] Removing task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.439378 3955 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.439873 3952 sched.cpp:327] Received status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000 from slave(41)@67.195.138.60:47553
I0427 05:29:48.440362 3957 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304270529-1015726915-47553-3718-0 from framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.441218 3955 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to executor(17)@67.195.138.60:47553
I0427 05:29:48.441728 3952 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 718e9c2f-ad57-4cc9-a533-9a6591e84fb5) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to slave(41)@67.195.138.60:47553
I0427 05:29:48.443219 3952 status_update_manager.cpp:359] Received status update acknowledgement 718e9c2f-ad57-4cc9-a533-9a6591e84fb5 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.443652 3952 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.444210 3952 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 718e9c2f-ad57-4cc9-a533-9a6591e84fb5 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.445654 3952 slave.cpp:2166] Cleaning up executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.446413 3954 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/e7084204-d206-4679-bdb8-0409280c1f0e' for removal
I0427 05:29:48.446431 3952 slave.cpp:2230] Cleaning up framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.446877 3954 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default' for removal
I0427 05:29:48.447326 3959 status_update_manager.cpp:251] Closing status update streams for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.447924 3954 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000' for removal
I0427 05:29:48.448079 3955 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.449481 3955 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0 to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.450217 3955 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 2.151441000000000ms
I0427 05:29:48.450268 3954 master.hpp:311] Adding offer 201304270529-1015726915-47553-3718-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.452105 3954 master.cpp:1375] Sending 1 offers to framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.452605 3953 sched.cpp:282] Received 1 offers
I0427 05:29:48.453244 3954 master.cpp:1608] Processing reply for offer 201304270529-1015726915-47553-3718-1 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org) for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.453644 3954 master.hpp:289] Adding task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.454243 3954 master.cpp:1727] Launching task 0 of framework 201304270529-1015726915-47553-3718-0000 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0 (janus.apache.org)
I0427 05:29:48.455348 3953 slave.cpp:721] Got assigned task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.455414 3954 master.hpp:321] Removing offer 201304270529-1015726915-47553-3718-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.455425 3956 hierarchical_allocator_process.hpp:471] Framework 201304270529-1015726915-47553-3718-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.457020 3956 hierarchical_allocator_process.hpp:497] Framework 201304270529-1015726915-47553-3718-0000 filtered slave 201304270529-1015726915-47553-3718-0 for 5.000000000000000secs
I0427 05:29:48.456363 3953 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000' for removal
I0427 05:29:48.458174 3955 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default' for removal
I0427 05:29:48.458544 3959 slave.cpp:819] Launching task 0 for framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.460209 3959 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/c7227594-52e2-4d04-89e4-e45bf049bca9'
I0427 05:29:48.460394 3959 slave.cpp:930] Queuing task '0' for executor default of framework '201304270529-1015726915-47553-3718-0000
I0427 05:29:48.460670 3959 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_XPAGFP/slaves/201304270529-1015726915-47553-3718-0/frameworks/201304270529-1015726915-47553-3718-0000/executors/default/runs/c7227594-52e2-4d04-89e4-e45bf049bca9'
I0427 05:29:48.460548 3955 exec.cpp:170] Executor started at: executor(18)@67.195.138.60:47553 with pid 3718
I0427 05:29:48.461629 3955 slave.cpp:1382] Got registration for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.462152 3955 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.462203 3957 exec.cpp:194] Executor registered on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.463141 3957 exec.cpp:258] Executor asked to run task '0'
I0427 05:29:48.464773 3957 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.464840 3957 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.465576 3957 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000 with checkpoint=false
I0427 05:29:48.465914 3957 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.466419 3957 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to master@67.195.138.60:47553
I0427 05:29:48.466963 3953 master.cpp:1134] Status update from (151)@67.195.138.60:47553: task 0 of framework 201304270529-1015726915-47553-3718-0000 is now in state TASK_RUNNING
I0427 05:29:48.466979 3956 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.467900 3956 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to executor(18)@67.195.138.60:47553
I0427 05:29:48.467433 3953 sched.cpp:327] Received status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000 from slave(41)@67.195.138.60:47553
I0427 05:29:48.468874 3954 exec.cpp:290] Executor received status update acknowledgement ��'�DH����_��n� for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.469307 3953 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: b1dd271c-b244-4882-9697-cd5fdbbe6eb1) for task 0 of framework 201304270529-1015726915-47553-3718-0000 to slave(41)@67.195.138.60:47553
I0427 05:29:48.470327 3953 status_update_manager.cpp:359] Received status update acknowledgement b1dd271c-b244-4882-9697-cd5fdbbe6eb1 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.470775 3953 slave.cpp:1332] Status update manager successfully handled status update acknowledgement b1dd271c-b244-4882-9697-cd5fdbbe6eb1 for task 0 of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.478283 3956 sched.cpp:422] Stopping framework '201304270529-1015726915-47553-3718-0000'
I0427 05:29:48.478288 3718 master.cpp:479] Master terminating
I0427 05:29:48.478749 3718 master.cpp:285] Shutting down master
I0427 05:29:48.478754 3957 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:47553
I0427 05:29:48.479199 3954 hierarchical_allocator_process.hpp:359] Deactivated framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.479218 3718 master.hpp:301] Removing task 0 with resources cpus=2; mem=1024 on slave 201304270529-1015726915-47553-3718-0
I0427 05:29:48.479635 3957 slave.cpp:1101] Asked to shut down framework 201304270529-1015726915-47553-3718-0000 by master@67.195.138.60:47553
I0427 05:29:48.480705 3958 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304270529-1015726915-47553-3718-0 from framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.481984 3957 slave.cpp:1126] Shutting down framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.482872 3957 slave.cpp:2299] Shutting down executor 'default' of framework 201304270529-1015726915-47553-3718-0000
I0427 05:29:48.483402 3957 slave.cpp:443] Slave terminating
I0427 05:29:48.483839 3957 slave.cpp:1101] Asked to shut down framework 201304270529-1015726915-47553-3718-0000 by @0.0.0.0:0
W0427 05:29:48.484314 3957 slave.cpp:1122] Ignoring shutdown framework 201304270529-1015726915-47553-3718-0000 because it is terminating
I0427 05:29:48.483417 3959 exec.cpp:323] Executor asked to shutdown
pure virtual method called
terminate called without an active exception
[ OK ] GarbageCollectorIntegrationTest.Unschedule (85 ms)
/bin/bash: line 5: 3718 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-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #317
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/317/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #316
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/316/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 8862 lines...]
I0430 03:35:16.595715 22908 master.cpp:1265] Sending 1 offers to framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.596215 22905 sched.cpp:282] Received 1 offers
I0430 03:35:16.596779 22908 master.cpp:1498] Processing reply for offer 201304300335-1015726915-48878-22880-0 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org) for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.597198 22908 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.597878 22908 master.cpp:1617] Launching task 0 of framework 201304300335-1015726915-48878-22880-0000 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org)
I0430 03:35:16.599081 22905 slave.cpp:721] Got assigned task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.599756 22905 slave.cpp:819] Launching task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.599176 22908 master.hpp:315] Removing offer 201304300335-1015726915-48878-22880-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.599145 22904 hierarchical_allocator_process.hpp:471] Framework 201304300335-1015726915-48878-22880-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.601129 22904 hierarchical_allocator_process.hpp:497] Framework 201304300335-1015726915-48878-22880-0000 filtered slave 201304300335-1015726915-48878-22880-0 for 5.000000000000000secs
I0430 03:35:16.601573 22905 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/dddf248a-1231-4925-8f2e-bb7ec7610960'
I0430 03:35:16.602123 22905 slave.cpp:930] Queuing task '0' for executor default of framework '201304300335-1015726915-48878-22880-0000
I0430 03:35:16.602534 22905 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/dddf248a-1231-4925-8f2e-bb7ec7610960'
I0430 03:35:16.602272 22907 exec.cpp:170] Executor started at: executor(16)@67.195.138.60:48878 with pid 22880
I0430 03:35:16.604420 22907 slave.cpp:1382] Got registration for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.604889 22907 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.604914 22901 exec.cpp:194] Executor registered on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.605792 22901 exec.cpp:258] Executor asked to run task '0'
I0430 03:35:16.607481 22901 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.607568 22908 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.608381 22901 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000 with checkpoint=false
I0430 03:35:16.608773 22901 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.609282 22901 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to master@67.195.138.60:48878
I0430 03:35:16.609765 22908 master.cpp:1048] Status update from slave(40)@67.195.138.60:48878: task 0 of framework 201304300335-1015726915-48878-22880-0000 is now in state TASK_RUNNING
I0430 03:35:16.609778 22906 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.610755 22906 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to executor(16)@67.195.138.60:48878
I0430 03:35:16.610260 22908 sched.cpp:327] Received status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000 from slave(40)@67.195.138.60:48878
I0430 03:35:16.611728 22908 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 39df0df0-a63c-48fa-ba52-985fd4c8329e) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to slave(40)@67.195.138.60:48878
I0430 03:35:16.611248 22902 exec.cpp:290] Executor received status update acknowledgement 9�
��<H��R�_��2� for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.611902 22904 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0430 03:35:16.612949 22907 status_update_manager.cpp:359] Received status update acknowledgement 39df0df0-a63c-48fa-ba52-985fd4c8329e for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.614236 22909 process.cpp:870] Socket closed while receiving
I0430 03:35:16.614351 22907 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 39df0df0-a63c-48fa-ba52-985fd4c8329e for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.615375 22907 slave.cpp:2031] Executor 'default' of framework 201304300335-1015726915-48878-22880-0000 has exited with status '0'
I0430 03:35:16.617079 22907 slave.cpp:1726] Handling status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.617238 22907 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000 with checkpoint=false
I0430 03:35:16.617665 22907 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to master@67.195.138.60:48878
I0430 03:35:16.617261 22901 master.cpp:1142] Executor default of framework 201304300335-1015726915-48878-22880-0000 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org) exited with status 0
I0430 03:35:16.618202 22907 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.619106 22907 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to executor(16)@67.195.138.60:48878
I0430 03:35:16.618702 22901 master.cpp:1048] Status update from slave(40)@67.195.138.60:48878: task 0 of framework 201304300335-1015726915-48878-22880-0000 is now in state TASK_LOST
I0430 03:35:16.620245 22901 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.620268 22902 sched.cpp:327] Received status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000 from slave(40)@67.195.138.60:48878
I0430 03:35:16.621398 22904 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304300335-1015726915-48878-22880-0 from framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.621754 22902 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: a4cffd1e-bd66-45a4-aa97-07c522f27b19) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to slave(40)@67.195.138.60:48878
I0430 03:35:16.623286 22906 status_update_manager.cpp:359] Received status update acknowledgement a4cffd1e-bd66-45a4-aa97-07c522f27b19 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.623723 22906 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.624248 22906 slave.cpp:1332] Status update manager successfully handled status update acknowledgement a4cffd1e-bd66-45a4-aa97-07c522f27b19 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.625257 22906 slave.cpp:2166] Cleaning up executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.625855 22900 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/dddf248a-1231-4925-8f2e-bb7ec7610960' for removal
I0430 03:35:16.626317 22900 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default' for removal
I0430 03:35:16.625869 22906 slave.cpp:2230] Cleaning up framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.627277 22906 status_update_manager.cpp:251] Closing status update streams for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.627328 22908 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000' for removal
I0430 03:35:16.636165 22905 slave.cpp:2482] Current disk usage 100.00%. Max allowed age: 0.000000000000000ns
I0430 03:35:16.636283 22905 gc.cpp:167] Pruning directories with remaining removal time 1.000000000000000weeks
I0430 03:35:16.636543 22905 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/dddf248a-1231-4925-8f2e-bb7ec7610960
I0430 03:35:16.637004 22905 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/dddf248a-1231-4925-8f2e-bb7ec7610960'
I0430 03:35:16.637696 22905 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default
I0430 03:35:16.638952 22905 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default'
I0430 03:35:16.639276 22905 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000
I0430 03:35:16.639798 22905 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_zj1Xdt/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000'
I0430 03:35:16.646451 22900 process.cpp:2921] Handling HTTP event for process 'files' with path: '/files/browse.json'
I0430 03:35:16.647016 22909 process.cpp:870] Socket closed while receiving
I0430 03:35:16.647299 22880 master.cpp:384] Master terminating
I0430 03:35:16.647438 22880 master.cpp:206] Shutting down master
I0430 03:35:16.647948 22904 hierarchical_allocator_process.hpp:359] Deactivated framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.647455 22900 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:48878
I0430 03:35:16.648921 22900 slave.cpp:443] Slave terminating
I0430 03:35:16.647318 22901 sched.cpp:422] Stopping framework '201304300335-1015726915-48878-22880-0000'
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (73 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0430 03:35:16.651896 22906 master.cpp:227] Master started on 67.195.138.60:48878
I0430 03:35:16.651958 22906 master.cpp:242] Master ID: 201304300335-1015726915-48878-22880
I0430 03:35:16.652721 22906 master.cpp:510] Elected as master!
W0430 03:35:16.652753 22900 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0430 03:35:16.652767 22904 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:48878
I0430 03:35:16.652781 22908 slave.cpp:212] Slave started on 41)@67.195.138.60:48878
I0430 03:35:16.656018 22908 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:35:16.657632 22908 slave.cpp:532] New master detected at master@67.195.138.60:48878
I0430 03:35:16.657678 22908 slave.cpp:547] Postponing registration until recovery is complete
I0430 03:35:16.658012 22908 slave.cpp:393] Finished recovery
I0430 03:35:16.657699 22907 status_update_manager.cpp:154] New master detected at master@67.195.138.60:48878
I0430 03:35:16.658994 22905 master.cpp:875] Attempting to register slave on janus.apache.org at slave(41)@67.195.138.60:48878
I0430 03:35:16.660778 22905 master.cpp:1828] Adding slave 201304300335-1015726915-48878-22880-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:35:16.661350 22901 slave.cpp:592] Registered with master master@67.195.138.60:48878; given slave ID 201304300335-1015726915-48878-22880-0
I0430 03:35:16.661424 22902 hierarchical_allocator_process.hpp:395] Added slave 201304300335-1015726915-48878-22880-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:35:16.662224 22902 hierarchical_allocator_process.hpp:632] No users to allocate resources!
I0430 03:35:16.662770 22902 hierarchical_allocator_process.hpp:619] Performed allocation for slave 201304300335-1015726915-48878-22880-0 in 550.381000000000085us
I0430 03:35:16.661877 22907 sched.cpp:182] New master at master@67.195.138.60:48878
I0430 03:35:16.663811 22904 master.cpp:553] Registering framework 201304300335-1015726915-48878-22880-0000 at scheduler(32)@67.195.138.60:48878
I0430 03:35:16.664505 22907 sched.cpp:217] Framework registered with 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.664530 22904 hierarchical_allocator_process.hpp:268] Added framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.666254 22904 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.666914 22904 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0 to framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.667407 22904 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.166407000000000ms
I0430 03:35:16.667446 22907 master.hpp:305] Adding offer 201304300335-1015726915-48878-22880-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.668309 22907 master.cpp:1265] Sending 1 offers to framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.668807 22901 sched.cpp:282] Received 1 offers
I0430 03:35:16.669414 22907 master.cpp:1498] Processing reply for offer 201304300335-1015726915-48878-22880-0 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org) for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.670794 22907 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.671773 22907 master.cpp:1617] Launching task 0 of framework 201304300335-1015726915-48878-22880-0000 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org)
I0430 03:35:16.672224 22901 slave.cpp:721] Got assigned task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.672806 22901 slave.cpp:819] Launching task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.672286 22907 master.hpp:315] Removing offer 201304300335-1015726915-48878-22880-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.672284 22902 hierarchical_allocator_process.hpp:471] Framework 201304300335-1015726915-48878-22880-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.674183 22902 hierarchical_allocator_process.hpp:497] Framework 201304300335-1015726915-48878-22880-0000 filtered slave 201304300335-1015726915-48878-22880-0 for 5.000000000000000secs
I0430 03:35:16.674561 22901 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/1d5a664c-af81-4ad0-b192-65cd2731a307'
I0430 03:35:16.675849 22901 slave.cpp:930] Queuing task '0' for executor default of framework '201304300335-1015726915-48878-22880-0000
I0430 03:35:16.676362 22901 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/1d5a664c-af81-4ad0-b192-65cd2731a307'
I0430 03:35:16.676023 22900 exec.cpp:170] Executor started at: executor(17)@67.195.138.60:48878 with pid 22880
I0430 03:35:16.677275 22900 slave.cpp:1382] Got registration for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.677830 22900 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.677865 22904 exec.cpp:194] Executor registered on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.678805 22904 exec.cpp:258] Executor asked to run task '0'
I0430 03:35:16.681182 22904 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.681252 22904 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.681679 22904 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000 with checkpoint=false
I0430 03:35:16.682046 22904 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.682586 22904 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to master@67.195.138.60:48878
I0430 03:35:16.683817 22904 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.683851 22908 master.cpp:1048] Status update from slave(41)@67.195.138.60:48878: task 0 of framework 201304300335-1015726915-48878-22880-0000 is now in state TASK_RUNNING
I0430 03:35:16.684254 22904 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to executor(17)@67.195.138.60:48878
I0430 03:35:16.684839 22905 sched.cpp:327] Received status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000 from slave(41)@67.195.138.60:48878
I0430 03:35:16.685408 22904 exec.cpp:290] Executor received status update acknowledgement �I����K:�mVn�r for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.686353 22905 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: 9c49e286-a0bd-4b3a-956d-566edb720c01) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to slave(41)@67.195.138.60:48878
I0430 03:35:16.687094 22900 slave.cpp:2031] Executor 'default' of framework 201304300335-1015726915-48878-22880-0000 has exited with status '0'
I0430 03:35:16.689677 22900 slave.cpp:1726] Handling status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.689904 22901 status_update_manager.cpp:289] Received status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000 with checkpoint=false
I0430 03:35:16.689916 22906 master.cpp:1142] Executor default of framework 201304300335-1015726915-48878-22880-0000 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org) exited with status 0
I0430 03:35:16.690150 22900 slave.cpp:1783] Status update manager successfully handled status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.691706 22900 slave.cpp:1789] Sending acknowledgement for status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to executor(17)@67.195.138.60:48878
I0430 03:35:16.690168 22901 status_update_manager.cpp:359] Received status update acknowledgement 9c49e286-a0bd-4b3a-956d-566edb720c01 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.692730 22901 status_update_manager.cpp:335] Forwarding status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to master@67.195.138.60:48878
I0430 03:35:16.693272 22906 master.cpp:1048] Status update from slave(41)@67.195.138.60:48878: task 0 of framework 201304300335-1015726915-48878-22880-0000 is now in state TASK_LOST
I0430 03:35:16.693302 22908 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 9c49e286-a0bd-4b3a-956d-566edb720c01 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.693740 22906 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.693763 22907 sched.cpp:327] Received status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000 from slave(41)@67.195.138.60:48878
I0430 03:35:16.695258 22907 sched.cpp:360] Sending ACK for status update TASK_LOST (UUID: 4f7fa8a7-2e32-4361-911a-583a936938c7) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to slave(41)@67.195.138.60:48878
I0430 03:35:16.694831 22902 hierarchical_allocator_process.hpp:544] Recovered cpus=2; mem=1024 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=1024) on slave 201304300335-1015726915-48878-22880-0 from framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.696694 22907 status_update_manager.cpp:359] Received status update acknowledgement 4f7fa8a7-2e32-4361-911a-583a936938c7 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.697573 22907 status_update_manager.cpp:480] Cleaning up status update stream for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.698128 22907 slave.cpp:1332] Status update manager successfully handled status update acknowledgement 4f7fa8a7-2e32-4361-911a-583a936938c7 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.698592 22907 slave.cpp:2166] Cleaning up executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.700256 22907 slave.cpp:2230] Cleaning up framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.700669 22901 status_update_manager.cpp:251] Closing status update streams for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.700342 22905 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/1d5a664c-af81-4ad0-b192-65cd2731a307' for removal
I0430 03:35:16.702829 22905 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default' for removal
I0430 03:35:16.703551 22905 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000' for removal
I0430 03:35:16.703693 22902 hierarchical_allocator_process.hpp:660] Found available resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.704620 22902 hierarchical_allocator_process.hpp:686] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0 to framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.705287 22902 hierarchical_allocator_process.hpp:599] Performed allocation for 1 slaves in 1.603661000000000ms
I0430 03:35:16.705335 22905 master.hpp:305] Adding offer 201304300335-1015726915-48878-22880-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.706153 22905 master.cpp:1265] Sending 1 offers to framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.706675 22907 sched.cpp:282] Received 1 offers
I0430 03:35:16.707314 22905 master.cpp:1498] Processing reply for offer 201304300335-1015726915-48878-22880-1 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org) for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.707691 22905 master.hpp:283] Adding task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.708850 22905 master.cpp:1617] Launching task 0 of framework 201304300335-1015726915-48878-22880-0000 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0 (janus.apache.org)
I0430 03:35:16.709391 22907 slave.cpp:721] Got assigned task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.709436 22906 hierarchical_allocator_process.hpp:471] Framework 201304300335-1015726915-48878-22880-0000 left ports=[31000-32000]; disk=1024 unused on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.709440 22905 master.hpp:315] Removing offer 201304300335-1015726915-48878-22880-1 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.710326 22907 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000' for removal
I0430 03:35:16.710578 22906 hierarchical_allocator_process.hpp:497] Framework 201304300335-1015726915-48878-22880-0000 filtered slave 201304300335-1015726915-48878-22880-0 for 5.000000000000000secs
I0430 03:35:16.711628 22908 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default' for removal
I0430 03:35:16.712728 22907 slave.cpp:819] Launching task 0 for framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.714371 22907 paths.hpp:302] Created executor directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/f696b9d4-29fb-4269-8a33-36b25c862714'
I0430 03:35:16.714555 22907 slave.cpp:930] Queuing task '0' for executor default of framework '201304300335-1015726915-48878-22880-0000
I0430 03:35:16.715495 22907 slave.cpp:514] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_RjOhLU/slaves/201304300335-1015726915-48878-22880-0/frameworks/201304300335-1015726915-48878-22880-0000/executors/default/runs/f696b9d4-29fb-4269-8a33-36b25c862714'
I0430 03:35:16.714668 22908 exec.cpp:170] Executor started at: executor(18)@67.195.138.60:48878 with pid 22880
I0430 03:35:16.717033 22908 slave.cpp:1382] Got registration for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.717525 22908 slave.cpp:1497] Flushing queued task 0 for executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.717555 22900 exec.cpp:194] Executor registered on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.718593 22900 exec.cpp:258] Executor asked to run task '0'
I0430 03:35:16.720255 22900 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.720320 22900 slave.cpp:1726] Handling status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.720737 22900 status_update_manager.cpp:289] Received status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000 with checkpoint=false
I0430 03:35:16.721293 22900 status_update_manager.cpp:449] Creating StatusUpdate stream for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.721792 22900 status_update_manager.cpp:335] Forwarding status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to master@67.195.138.60:48878
I0430 03:35:16.722951 22900 master.cpp:1048] Status update from slave(41)@67.195.138.60:48878: task 0 of framework 201304300335-1015726915-48878-22880-0000 is now in state TASK_RUNNING
I0430 03:35:16.722959 22904 slave.cpp:1783] Status update manager successfully handled status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.723850 22904 slave.cpp:1789] Sending acknowledgement for status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to executor(18)@67.195.138.60:48878
I0430 03:35:16.723435 22906 sched.cpp:327] Received status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000 from slave(41)@67.195.138.60:48878
I0430 03:35:16.724355 22900 exec.cpp:290] Executor received status update acknowledgement ��8��Kt��k�u~T for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.725527 22906 sched.cpp:360] Sending ACK for status update TASK_RUNNING (UUID: fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54) for task 0 of framework 201304300335-1015726915-48878-22880-0000 to slave(41)@67.195.138.60:48878
I0430 03:35:16.726557 22906 status_update_manager.cpp:359] Received status update acknowledgement fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.727771 22906 slave.cpp:1332] Status update manager successfully handled status update acknowledgement fbbf38d5-17f6-4b74-bdf0-6bcc1b757e54 for task 0 of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.733868 22880 master.cpp:384] Master terminating
I0430 03:35:16.733934 22880 master.cpp:206] Shutting down master
I0430 03:35:16.734273 22880 master.hpp:295] Removing task 0 with resources cpus=2; mem=1024 on slave 201304300335-1015726915-48878-22880-0
I0430 03:35:16.733878 22900 sched.cpp:422] Stopping framework '201304300335-1015726915-48878-22880-0000'
I0430 03:35:16.734313 22901 hierarchical_allocator_process.hpp:359] Deactivated framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.733974 22904 slave.cpp:488] Slave asked to shut down by master@67.195.138.60:48878
I0430 03:35:16.736349 22904 slave.cpp:1101] Asked to shut down framework 201304300335-1015726915-48878-22880-0000 by master@67.195.138.60:48878
I0430 03:35:16.736815 22904 slave.cpp:1126] Shutting down framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.737293 22904 slave.cpp:2299] Shutting down executor 'default' of framework 201304300335-1015726915-48878-22880-0000
I0430 03:35:16.738615 22904 slave.cpp:443] Slave terminating
I0430 03:35:16.739279 22904 slave.cpp:1101] Asked to shut down framework 201304300335-1015726915-48878-22880-0000 by @0.0.0.0:0
W0430 03:35:16.739814 22904 slave.cpp:1122] Ignoring shutdown framework 201304300335-1015726915-48878-22880-0000 because it is terminating
[ OK ] GarbageCollectorIntegrationTest.Unschedule (90 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (381 ms total)
[----------] 8 tests from ResourceOffersTest
[ RUN ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
I0430 03:35:16.738662 22907 exec.cpp:323] Executor asked to shutdown
#
# A fatal error has been detected by the Java Runtime Environment:
#
# SIGILL (0x4) at pc=0x00007fff1c63d050, pid=22880, tid=47793632716544
#
# JRE version: 6.0_20-b02
# Java VM: Java HotSpot(TM) 64-Bit Server VM (16.3-b01 mixed mode linux-amd64 )
# Problematic frame:
# C 0x00007fff1c63d050
#
# An error report file with more information is saved as:
# <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src/hs_err_pid22880.log>
I0430 03:35:16.741408 22906 master.cpp:227] Master started on 67.195.138.60:48878
I0430 03:35:16.741763 22906 master.cpp:242] Master ID: 201304300335-1015726915-48878-22880
I0430 03:35:16.741996 22901 slave.cpp:212] Slave started on 42)@67.195.138.60:48878
I0430 03:35:16.742708 22901 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:35:16.742591 22906 hierarchical_allocator_process.hpp:236] Initializing hierarchical allocator process with master : master@67.195.138.60:48878
I0430 03:35:16.743911 22906 slave.cpp:212] Slave started on 45)@67.195.138.60:48878
I0430 03:35:16.744365 22906 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:35:16.743448 22900 slave.cpp:212] Slave started on 44)@67.195.138.60:48878
I0430 03:35:16.742622 22904 slave.cpp:212] Slave started on 43)@67.195.138.60:48878
I0430 03:35:16.746074 22904 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
W0430 03:35:16.742563 22908 master.cpp:82] No whitelist given. Advertising offers for all slaves
I0430 03:35:16.744396 22902 slave.cpp:212] Slave started on 46)@67.195.138.60:48878
I0430 03:35:16.764801 22902 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
I0430 03:35:16.745287 22906 process_isolator.cpp:303] Recovering isolator
#
# If you would like to submit a bug report, please visit:
# http://java.sun.com/webapps/bugreport/crash.jsp
#
I0430 03:35:16.745350 22900 slave.cpp:213] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=1024
/bin/bash: line 5: 22880 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-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure