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 2014/10/26 08:20:13 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2212
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2212/changes>
Changes:
[yujie.jay] Added a check in routing library due to a bug in libnl.
[yujie.jay] Added documentation for egress rate limit control.
[yujie.jay] Reordered functions in type_utils and added an equal comparator for
------------------------------------------
[...truncated 14718 lines...]
I1026 06:53:14.912662 27980 hierarchical_allocator_process.hpp:563] Recovered mem(*):9984; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: mem(*):9984; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-065048-3142697795-44146-27951-S1 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.016216 27980 hierarchical_allocator_process.hpp:599] Framework 20141026-065048-3142697795-44146-27951-0000 filtered slave 20141026-065048-3142697795-44146-27951-S1 for 5secs
I1026 06:53:19.003070 27978 slave.cpp:2522] Received ping from slave-observer(2)@67.195.81.187:44146
I1026 06:53:20.016254 27984 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.016244 27987 status_update_manager.cpp:317] Received status update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
Received an UPDATE event
Task 1 is in state TASK_RUNNING
I1026 06:53:20.017233 28232 exec.cpp:339] Executor received status update acknowledgement 20972b49-0608-425f-aa6e-1be91e1aa2ab for task 4 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.016341 27978 slave.cpp:2369] Status update manager successfully handled status update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.016460 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:19.909863 27985 master.cpp:120] No whitelist given. Advertising offers for all slaves
I1026 06:53:20.016798 27980 hierarchical_allocator_process.hpp:816] Filtered mem(*):9984; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-065048-3142697795-44146-27951-S1 for framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:14.912722 27983 slave.cpp:2369] Status update manager successfully handled status update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.097728 27978 slave.cpp:2375] Sending acknowledgement for status update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 to executor(1)@67.195.81.187:58953
I1026 06:53:20.097805 27983 slave.cpp:2375] Sending acknowledgement for status update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 to executor(1)@67.195.81.187:40160
I1026 06:53:20.097816 27980 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 81.473008ms
W1026 06:53:20.097868 27989 master.cpp:3375] Could not lookup task for status update TASK_FINISHED (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.098006 27986 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.098022 27980 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):1; mem(*):128) on slave 20141026-065048-3142697795-44146-27951-S0 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098042 27989 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098136 27983 slave.cpp:2522] Received ping from slave-observer(1)@67.195.81.187:44146
I1026 06:53:20.098155 27978 slave.cpp:2369] Status update manager successfully handled status update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098168 27989 master.cpp:3382] Status update TASK_RUNNING (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
E1026 06:53:20.098202 27989 master.cpp:4567] Ignoring out of order status update for task 1 (TASK_FINISHED -> TASK_RUNNING) of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098191 27978 slave.cpp:2375] Sending acknowledgement for status update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 to executor(1)@67.195.81.187:58953
E1026 06:53:20.098350 27983 slave.cpp:1782] Failed to handle status update acknowledgement (UUID: a7b6649c-90d1-4a52-8828-0f2222360a01) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000: Duplicate acknowledgement
I1026 06:53:20.098378 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098414 27983 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098426 27986 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.098469 28196 exec.cpp:339] Executor received status update acknowledgement 8c93b0fe-9641-4927-9a91-f9860cb6d199 for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098484 27983 slave.cpp:4204] Completing task 0
W1026 06:53:20.098484 27989 master.cpp:3375] Could not lookup task for status update TASK_FINISHED (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.098587 27983 slave.cpp:3297] Current usage 8.90%. Max allowed age: 5.676754280608171days
I1026 06:53:20.098657 27989 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: 004d5bd4-faa4-4e6d-99f8-35d0b0fe03f0) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000
Received an UPDATE event
Task 0 is in state TASK_FINISHED
I1026 06:53:20.098739 27989 master.cpp:3382] Status update TASK_RUNNING (UUID: 004d5bd4-faa4-4e6d-99f8-35d0b0fe03f0) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S2 at slave(2)@67.195.81.187:44146 (pomona.apache.org)
Received an UPDATE event
Task 1 is in state TASK_RUNNING
I1026 06:53:20.098773 27989 master.cpp:4617] Updating the latest state of task 4 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED (status update state: TASK_RUNNING)
I1026 06:53:20.098794 27986 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.098922 28220 exec.cpp:339] Executor received status update acknowledgement 41053515-4500-4495-9af0-e24b9bcae52f for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098968 27989 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: 746c870c-ef01-4f21-848e-1456a5e1c684) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.098980 27984 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):1; mem(*):128) on slave 20141026-065048-3142697795-44146-27951-S2 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.099030 28220 exec.cpp:339] Executor received status update acknowledgement f144a76b-2ef7-4c86-95e0-a58cfc28e2e1 for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.099040 27989 master.cpp:3382] Status update TASK_RUNNING (UUID: 746c870c-ef01-4f21-848e-1456a5e1c684) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.099083 27989 master.cpp:4617] Updating the latest state of task 2 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED (status update state: TASK_RUNNING)
I1026 06:53:20.099104 27986 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.099362 27990 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1) on slave 20141026-065048-3142697795-44146-27951-S1 from framework 20141026-065048-3142697795-44146-27951-0000
Received an UPDATE event
Task 0 is in state TASK_FINISHED
I1026 06:53:20.099699 27986 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
Received an UPDATE event
Task 4 is in state TASK_RUNNING
I1026 06:53:20.099681 27990 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-065048-3142697795-44146-27951-S2 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.099761 27990 hierarchical_allocator_process.hpp:599] Framework 20141026-065048-3142697795-44146-27951-0000 filtered slave 20141026-065048-3142697795-44146-27951-S2 for 5secs
I1026 06:53:20.099890 27989 master.cpp:2321] Processing reply for offers: [ 20141026-065048-3142697795-44146-27951-O10 ] on slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org) for framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146
I1026 06:53:20.100306 27989 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: eb092f3d-c04a-4e1e-a3ce-cadcfaaa0039) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
Received an UPDATE event
Task 2 is in state TASK_RUNNING
I1026 06:53:20.100414 27989 master.cpp:3382] Status update TASK_RUNNING (UUID: eb092f3d-c04a-4e1e-a3ce-cadcfaaa0039) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.100456 27989 master.cpp:4617] Updating the latest state of task 3 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED (status update state: TASK_RUNNING)
I1026 06:53:20.100467 27975 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.100699 27989 master.cpp:2882] Forwarding status update acknowledgement 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3 for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.100775 27976 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2) on slave 20141026-065048-3142697795-44146-27951-S1 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.100811 27989 master.cpp:2882] Forwarding status update acknowledgement 3e12ee07-a86d-4661-8fd2-f5e189258483 for task 0 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.100925 27985 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.100955 27989 master.cpp:2882] Forwarding status update acknowledgement 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3 for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.101043 27985 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 to the slave
Received an UPDATE event
Task 3 is in state TASK_RUNNING
I1026 06:53:20.101116 27989 master.cpp:2882] Forwarding status update acknowledgement 3e12ee07-a86d-4661-8fd2-f5e189258483 for task 0 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.101264 27989 master.cpp:2882] Forwarding status update acknowledgement 004d5bd4-faa4-4e6d-99f8-35d0b0fe03f0 for task 4 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S2 at slave(2)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.101308 27985 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101393 27984 slave.cpp:2442] Forwarding the update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 to master@67.195.81.187:44146
I1026 06:53:20.101493 27985 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101526 27977 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 004d5bd4-faa4-4e6d-99f8-35d0b0fe03f0) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000
W1026 06:53:20.101562 27985 status_update_manager.hpp:263] Duplicate status update acknowledgment (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101614 27989 master.cpp:2882] Forwarding status update acknowledgement 746c870c-ef01-4f21-848e-1456a5e1c684 for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.101642 27984 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101646 27977 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: 20972b49-0608-425f-aa6e-1be91e1aa2ab) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000 to the slave
I1026 06:53:20.101706 27985 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
E1026 06:53:20.101716 27984 slave.cpp:1782] Failed to handle status update acknowledgement (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000: Cannot find the status update stream for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
E1026 06:53:20.101750 27984 slave.cpp:1782] Failed to handle status update acknowledgement (UUID: 44cb2fbb-5bdb-4a10-838a-0283fab0f0a3) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000: Duplicate acknowledgement
I1026 06:53:20.101805 27989 master.cpp:2882] Forwarding status update acknowledgement eb092f3d-c04a-4e1e-a3ce-cadcfaaa0039 for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.101819 27984 slave.cpp:2442] Forwarding the update TASK_FINISHED (UUID: 20972b49-0608-425f-aa6e-1be91e1aa2ab) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000 to master@67.195.81.187:44146
I1026 06:53:20.101855 27983 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 746c870c-ef01-4f21-848e-1456a5e1c684) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
E1026 06:53:20.101856 27985 slave.cpp:1782] Failed to handle status update acknowledgement (UUID: 3e12ee07-a86d-4661-8fd2-f5e189258483) for task 0 of framework 20141026-065048-3142697795-44146-27951-0000: Cannot find the status update stream for task 0 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101899 27984 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 004d5bd4-faa4-4e6d-99f8-35d0b0fe03f0) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101871 27988 hierarchical_allocator_process.hpp:563] Recovered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-065048-3142697795-44146-27951-S0 from framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.101959 27988 hierarchical_allocator_process.hpp:599] Framework 20141026-065048-3142697795-44146-27951-0000 filtered slave 20141026-065048-3142697795-44146-27951-S0 for 5secs
I1026 06:53:20.101976 27983 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 to the slave
I1026 06:53:20.102008 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102125 27989 master.cpp:3382] Status update TASK_FINISHED (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.102159 27976 slave.cpp:2442] Forwarding the update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 to master@67.195.81.187:44146
I1026 06:53:20.102180 27989 master.cpp:4617] Updating the latest state of task 1 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED
I1026 06:53:20.102170 27977 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.102265 27976 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 746c870c-ef01-4f21-848e-1456a5e1c684) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102267 27983 status_update_manager.cpp:389] Received status update acknowledgement (UUID: eb092f3d-c04a-4e1e-a3ce-cadcfaaa0039) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102399 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: 20972b49-0608-425f-aa6e-1be91e1aa2ab) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102454 27983 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 to the slave
I1026 06:53:20.102516 27989 master.cpp:3382] Status update TASK_FINISHED (UUID: 20972b49-0608-425f-aa6e-1be91e1aa2ab) for task 4 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S2 at slave(2)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.102562 27989 master.cpp:4617] Updating the latest state of task 4 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED
Received an UPDATE event
Task 1 is in state TASK_FINISHED
I1026 06:53:20.102670 27986 slave.cpp:2442] Forwarding the update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 to master@67.195.81.187:44146
I1026 06:53:20.102709 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102761 27977 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.102780 27986 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: eb092f3d-c04a-4e1e-a3ce-cadcfaaa0039) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.102818 27989 master.cpp:3382] Status update TASK_FINISHED (UUID: 41053515-4500-4495-9af0-e24b9bcae52f) for task 2 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.102857 27989 master.cpp:4617] Updating the latest state of task 2 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED
I1026 06:53:20.102977 27989 master.cpp:3410] Forwarding status update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.103066 27989 master.cpp:3382] Status update TASK_FINISHED (UUID: f144a76b-2ef7-4c86-95e0-a58cfc28e2e1) for task 3 of framework 20141026-065048-3142697795-44146-27951-0000 from slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.103106 27989 master.cpp:4617] Updating the latest state of task 3 of framework 20141026-065048-3142697795-44146-27951-0000 to TASK_FINISHED
I1026 06:53:20.103176 27977 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
I1026 06:53:20.103224 27989 master.cpp:4676] Removing task 1 with resources cpus(*):1; mem(*):128 of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.103405 27989 master.cpp:2882] Forwarding status update acknowledgement 8c93b0fe-9641-4927-9a91-f9860cb6d199 for task 1 of framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 to slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
Received an UPDATE event
Task 4 is in state TASK_FINISHED
I1026 06:53:20.103504 27977 scheduler.cpp:582] Enqueuing event 5 from master@67.195.81.187:44146
Received an UPDATE event
Task 2 is in state TASK_FINISHED
I1026 06:53:20.103653 27990 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.103763 27990 status_update_manager.cpp:525] Cleaning up status update stream for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.103907 27976 master.cpp:768] Framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 disconnected
I1026 06:53:20.103930 27976 master.cpp:1731] Disconnecting framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146
I1026 06:53:20.103971 27976 master.cpp:1747] Deactivating framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146
I1026 06:53:20.104046 27977 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 8c93b0fe-9641-4927-9a91-f9860cb6d199) for task 1 of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.104085 27978 hierarchical_allocator_process.hpp:405] Deactivated framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.104096 27976 master.cpp:790] Giving framework 20141026-065048-3142697795-44146-27951-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:44146 0ns to failover
I1026 06:53:20.104105 27977 slave.cpp:4204] Completing task 1
I1026 06:53:20.104163 27976 master.cpp:677] Master terminating
I1026 06:53:20.104441 27976 master.cpp:4676] Removing task 4 with resources cpus(*):1; mem(*):128 of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S2 at slave(2)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.104835 27976 master.cpp:4705] Removing executor 'default' with resources of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S2 at slave(2)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.105294 27976 master.cpp:4676] Removing task 3 with resources cpus(*):1; mem(*):128 of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.105427 27976 master.cpp:4676] Removing task 2 with resources cpus(*):1; mem(*):128 of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.105708 27976 master.cpp:4705] Removing executor 'default' with resources of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S1 at slave(1)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.106106 27976 master.cpp:4705] Removing executor 'default' with resources of framework 20141026-065048-3142697795-44146-27951-0000 on slave 20141026-065048-3142697795-44146-27951-S0 at slave(3)@67.195.81.187:44146 (pomona.apache.org)
I1026 06:53:20.106676 27976 slave.cpp:2607] master@67.195.81.187:44146 exited
W1026 06:53:20.106695 27976 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected
I1026 06:53:20.106688 27989 slave.cpp:2607] master@67.195.81.187:44146 exited
I1026 06:53:20.106704 27976 slave.cpp:2607] master@67.195.81.187:44146 exited
W1026 06:53:20.106712 27976 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected
W1026 06:53:20.106712 27989 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected
I1026 06:53:20.109755 27951 slave.cpp:484] Slave terminating
I1026 06:53:20.110013 27951 slave.cpp:1522] Asked to shut down framework 20141026-065048-3142697795-44146-27951-0000 by @0.0.0.0:0
I1026 06:53:20.110041 27951 slave.cpp:1547] Shutting down framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.110270 27951 slave.cpp:3122] Shutting down executor 'default' of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.110765 28220 exec.cpp:379] Executor asked to shutdown
I1026 06:53:20.110838 28220 exec.cpp:394] Executor::shutdown took 1785ns
I1026 06:53:20.110859 28220 exec.cpp:78] Scheduling shutdown of the executor
I1026 06:53:20.112377 27984 slave.cpp:484] Slave terminating
I1026 06:53:20.112444 27984 slave.cpp:1522] Asked to shut down framework 20141026-065048-3142697795-44146-27951-0000 by @0.0.0.0:0
I1026 06:53:20.112473 27984 slave.cpp:1547] Shutting down framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.112529 27984 slave.cpp:3122] Shutting down executor 'default' of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.112901 28211 exec.cpp:379] Executor asked to shutdown
I1026 06:53:20.112970 28211 exec.cpp:394] Executor::shutdown took 2182ns
I1026 06:53:20.113001 28237 exec.cpp:78] Scheduling shutdown of the executor
I1026 06:53:20.114002 27951 slave.cpp:484] Slave terminating
I1026 06:53:20.114073 27951 slave.cpp:1522] Asked to shut down framework 20141026-065048-3142697795-44146-27951-0000 by @0.0.0.0:0
I1026 06:53:20.114099 27951 slave.cpp:1547] Shutting down framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.114161 27951 slave.cpp:3122] Shutting down executor 'default' of framework 20141026-065048-3142697795-44146-27951-0000
I1026 06:53:20.114594 28203 exec.cpp:379] Executor asked to shutdown
I1026 06:53:20.114650 28203 exec.cpp:394] Executor::shutdown took 1525ns
I1026 06:53:20.114681 28203 exec.cpp:78] Scheduling shutdown of the executor
I1026 06:53:25.115129 28238 exec.cpp:86] Committing suicide by killing the process group
II1026 06:53:25.117336 28211 exec.cpp:86] Committing suicide by killing the process group
1026 06:53:25.117332 28197 exec.cpp:86] Committing suicide by killing the process group
Sun Oct 26 07:20:11 UTC 2014: process still running after 1800 seconds
==========
Attaching gdb to 27135 bash ./support/timed_tests.sh GLOG_v=1 MESOS_VERBOSE=1 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml: 1800
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27138 bash ./support/timed_tests.sh GLOG_v=1 MESOS_VERBOSE=1 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml: 1800
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27140 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml:
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27144 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if test -n ' Makefile' && test -n '0'; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \.]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs.]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='. 3rdparty src ec2' ;; \ *) list='. 3rdparty src ec2' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail"
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27290 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if test -n ' Makefile' && test -n '0'; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \.]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs.]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='. 3rdparty src ec2' ;; \ *) list='. 3rdparty src ec2' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail"
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27291 make check
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27292 make check-am
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27336 make check-local
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27342 <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src/.libs/lt-mesos-tests>
==========
./support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 27951 <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src/.libs> lt-low-level-scheduler-pthread --master=local
==========
./support/timed_tests.sh: line 72: gdb: command not found
Test failed and killing the stuck test process
/tmp/hudson5641794646970677363.sh: line 18: 27135 Terminated ./support/timed_tests.sh "GLOG_v=1 MESOS_VERBOSE=1 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml:" 1800
make: *** [check-recursive] Terminated
tests/script.cpp:83: Failure
Failed
low_level_scheduler_pthread_test.sh terminated with signal Terminated
[ FAILED ] ExamplesTest.LowLevelSchedulerPthread (1764064 ms)
[ RUN ] ExamplesTest.JavaException
Build step 'Execute shell' marked build as failure
Recording test results
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2213
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2213/changes>