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:35:17 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #316
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
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>