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 04:29:55 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#2487
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2487/changes>
Changes:
[benjamin.hindman] Introduced a FutureResult action.
------------------------------------------
[...truncated 31020 lines...]
I1026 03:29:35.996134 9107 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-030030-3142697795-38676-9075-S2 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:35.996181 9107 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S2 for 5secs
I1026 03:29:36.001066 9103 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:36.001900 9099 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:36.096349 9101 master.cpp:120] No whitelist given. Advertising offers for all slaves
I1026 03:29:36.994582 9111 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:36.994772 9111 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:36.994926 9111 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:36.994982 9111 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 897793ns
I1026 03:29:37.001737 9102 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:37.002626 9099 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:37.996212 9099 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:37.996426 9099 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:37.996583 9099 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:37.996649 9099 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 870348ns
I1026 03:29:38.002389 9099 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:38.003495 9114 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:38.997797 9112 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:38.997989 9112 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:38.998142 9112 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:38.998195 9112 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 781708ns
I1026 03:29:39.002854 9106 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:39.003757 9114 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:39.999658 9113 hierarchical_allocator_process.hpp:734] Offering mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:40.000136 9113 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:40.000325 9113 hierarchical_allocator_process.hpp:734] Offering mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:40.000725 9113 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.391893ms
I1026 03:29:40.001190 9106 master.cpp:3795] Sending 2 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:40.001523 9107 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1047 with mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2
Received offer 20141026-030030-3142697795-38676-9075-O1048 with mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1
I1026 03:29:40.003018 9113 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1047 ] on slave 20141026-030030-3142697795-38676-9075-S0 at slave(3)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:40.003551 9113 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1048 ] on slave 20141026-030030-3142697795-38676-9075-S1 at slave(2)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:40.003676 9111 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:40.004156 9103 hierarchical_allocator_process.hpp:563] Recovered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 (total allocatable: mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2) on slave 20141026-030030-3142697795-38676-9075-S0 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:40.004207 9103 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S0 for 5secs
I1026 03:29:40.004439 9103 hierarchical_allocator_process.hpp:563] Recovered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 (total allocatable: mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1) on slave 20141026-030030-3142697795-38676-9075-S1 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:40.004479 9103 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S1 for 5secs
I1026 03:29:40.004675 9109 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:41.001668 9102 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:41.001858 9102 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:41.001994 9102 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:41.002359 9102 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.039056ms
I1026 03:29:41.002701 9111 master.cpp:3795] Sending 1 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:41.002928 9100 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1049 with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1026 03:29:41.004215 9107 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1049 ] on slave 20141026-030030-3142697795-38676-9075-S2 at slave(1)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:41.004686 9102 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:41.004860 9100 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-030030-3142697795-38676-9075-S2 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:41.004907 9100 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S2 for 5secs
I1026 03:29:41.005672 9109 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:41.096922 9099 master.cpp:120] No whitelist given. Advertising offers for all slaves
I1026 03:29:42.003823 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:42.004019 9110 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:42.004175 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:42.004233 9110 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 793636ns
I1026 03:29:42.005992 9111 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:42.006889 9111 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:43.005342 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:43.005558 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:43.005717 9110 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:43.005774 9110 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.001405ms
I1026 03:29:43.007498 9105 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:43.008430 9114 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:44.006937 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:44.007133 9110 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:44.007300 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:44.007356 9110 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 821020ns
I1026 03:29:44.007943 9102 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:44.008838 9100 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:45.008569 9112 hierarchical_allocator_process.hpp:734] Offering mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:45.009258 9112 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:45.009496 9112 hierarchical_allocator_process.hpp:734] Offering mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:45.009675 9105 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:45.009737 9105 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:45.010121 9112 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 2.136252ms
I1026 03:29:45.010596 9110 master.cpp:3795] Sending 2 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:45.010845 9102 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1050 with mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2
Received offer 20141026-030030-3142697795-38676-9075-O1051 with mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1
I1026 03:29:45.012629 9109 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1050 ] on slave 20141026-030030-3142697795-38676-9075-S0 at slave(3)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:45.013389 9109 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1051 ] on slave 20141026-030030-3142697795-38676-9075-S1 at slave(2)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:45.014019 9113 hierarchical_allocator_process.hpp:563] Recovered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 (total allocatable: mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2) on slave 20141026-030030-3142697795-38676-9075-S0 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:45.014075 9113 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S0 for 5secs
I1026 03:29:45.014318 9113 hierarchical_allocator_process.hpp:563] Recovered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 (total allocatable: mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1) on slave 20141026-030030-3142697795-38676-9075-S1 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:45.014367 9113 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S1 for 5secs
I1026 03:29:46.011147 9103 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:46.011185 9106 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:46.011301 9106 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:46.011600 9103 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:46.011770 9103 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:46.011901 9103 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.234419ms
I1026 03:29:46.012356 9101 master.cpp:3795] Sending 1 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:46.012586 9110 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1052 with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1026 03:29:46.014097 9101 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1052 ] on slave 20141026-030030-3142697795-38676-9075-S2 at slave(1)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:46.014763 9101 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-030030-3142697795-38676-9075-S2 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:46.014818 9101 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S2 for 5secs
I1026 03:29:46.042258 9109 slave.cpp:2522] Received ping from slave-observer(1)@67.195.81.187:38676
I1026 03:29:46.097422 9109 master.cpp:120] No whitelist given. Advertising offers for all slaves
I1026 03:29:46.214970 9109 slave.cpp:2522] Received ping from slave-observer(2)@67.195.81.187:38676
I1026 03:29:46.696545 9109 slave.cpp:2522] Received ping from slave-observer(3)@67.195.81.187:38676
I1026 03:29:47.012506 9105 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:47.012595 9105 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:47.012742 9108 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:47.012926 9108 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:47.013088 9108 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:47.013144 9108 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 998909ns
I1026 03:29:48.013484 9107 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:48.013577 9107 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:48.014477 9110 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:48.014668 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:48.014832 9110 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:48.014888 9110 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 798292ns
I1026 03:29:49.014878 9111 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:49.014960 9111 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:49.015940 9104 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:49.016131 9104 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:49.016351 9104 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:49.016410 9104 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 896281ns
I1026 03:29:50.015990 9105 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:50.016100 9103 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:50.017040 9108 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:50.017211 9108 hierarchical_allocator_process.hpp:734] Offering mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:50.017573 9108 hierarchical_allocator_process.hpp:734] Offering mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:50.017954 9108 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.229661ms
I1026 03:29:50.018393 9101 master.cpp:3795] Sending 2 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:50.018656 9111 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1053 with mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2
Received offer 20141026-030030-3142697795-38676-9075-O1054 with mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1
I1026 03:29:50.020421 9114 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1053 ] on slave 20141026-030030-3142697795-38676-9075-S0 at slave(3)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:50.021181 9114 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1054 ] on slave 20141026-030030-3142697795-38676-9075-S1 at slave(2)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:50.022032 9099 hierarchical_allocator_process.hpp:563] Recovered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 (total allocatable: mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2) on slave 20141026-030030-3142697795-38676-9075-S0 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:50.022094 9099 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S0 for 5secs
I1026 03:29:50.022325 9099 hierarchical_allocator_process.hpp:563] Recovered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 (total allocatable: mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1) on slave 20141026-030030-3142697795-38676-9075-S1 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:50.022363 9099 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S1 for 5secs
I1026 03:29:51.017604 9102 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:51.017704 9102 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:51.018636 9113 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:51.018810 9113 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 to framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:51.019166 9113 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:51.019299 9113 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 989058ns
I1026 03:29:51.019604 9106 master.cpp:3795] Sending 1 offers to framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:51.019788 9106 scheduler.cpp:582] Enqueuing event 3 from master@67.195.81.187:38676
Received an OFFERS event
Received offer 20141026-030030-3142697795-38676-9075-O1055 with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1026 03:29:51.021168 9100 master.cpp:2321] Processing reply for offers: [ 20141026-030030-3142697795-38676-9075-O1055 ] on slave 20141026-030030-3142697795-38676-9075-S2 at slave(1)@67.195.81.187:38676 (pomona.apache.org) for framework 20141026-030030-3142697795-38676-9075-0000 (Low-Level Scheduler using pthread (C++)) at scheduler(1)@67.195.81.187:38676
I1026 03:29:51.021803 9105 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]) on slave 20141026-030030-3142697795-38676-9075-S2 from framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:51.021850 9105 hierarchical_allocator_process.hpp:599] Framework 20141026-030030-3142697795-38676-9075-0000 filtered slave 20141026-030030-3142697795-38676-9075-S2 for 5secs
I1026 03:29:51.097651 9108 master.cpp:120] No whitelist given. Advertising offers for all slaves
I1026 03:29:52.018596 9108 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:52.018683 9108 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:52.021006 9105 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:52.021198 9105 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:52.021368 9105 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:52.021422 9105 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 1.16354ms
I1026 03:29:53.019745 9111 monitor.cpp:140] Failed to collect resource usage for container 'b51d53cf-1654-4f82-bd2c-9e8884702378' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: b51d53cf-1654-4f82-bd2c-9e8884702378
I1026 03:29:53.019817 9111 monitor.cpp:140] Failed to collect resource usage for container 'c89d7949-b121-4ec4-a935-f0848128cd4f' for executor 'default' of framework '20141026-030030-3142697795-38676-9075-0000': Unknown container: c89d7949-b121-4ec4-a935-f0848128cd4f
I1026 03:29:53.022776 9100 hierarchical_allocator_process.hpp:816] Filtered mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):2 on slave 20141026-030030-3142697795-38676-9075-S0 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:53.022950 9100 hierarchical_allocator_process.hpp:816] Filtered mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000]; cpus(*):1 on slave 20141026-030030-3142697795-38676-9075-S1 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:53.023102 9100 hierarchical_allocator_process.hpp:816] Filtered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] on slave 20141026-030030-3142697795-38676-9075-S2 for framework 20141026-030030-3142697795-38676-9075-0000
I1026 03:29:53.023157 9100 hierarchical_allocator_process.hpp:659] Performed allocation for 3 slaves in 768747ns
Sun Oct 26 03:29:53 UTC 2014: process still running after 1800 seconds
==========
Attaching gdb to 7527 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 7530 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 7532 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml:
==========
../support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 7536 /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 7669 /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 7670 make check
==========
../support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 7671 make check-am
==========
../support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 7678 make check-local
==========
../support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 7679 <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src/.libs/lt-mesos-tests>
==========
../support/timed_tests.sh: line 72: gdb: command not found
==========
Attaching gdb to 9075 <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/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
W1026 03:00:15.550634 7679 logging.cpp:81] RAW: Received signal SIGTERM from process 27769 of user 2395; exiting
make: *** [check-recursive] Terminated
/tmp/hudson729662550043293025.sh: line 16: 7527 Terminated ../support/timed_tests.sh "GLOG_v=1 MESOS_VERBOSE=1 make check GTEST_SHUFFLE=1 GTEST_OUTPUT=xml:" 1800
I1026 03:29:53.453454 9309 exec.cpp:459] Slave exited ... shutting down
I1026 03:29:53.453476 9274 exec.cpp:459] Slave exited ... shutting down
I1026 03:29:53.453555 9309 exec.cpp:476] Executor::shutdown took 1704ns
I1026 03:29:53.453567 9274 exec.cpp:476] Executor::shutdown took 2200ns
II1026 03:29:53.453589 9309 exec.cpp:78] Scheduling shutdown of the executor
1026 03:29:53.453587 9271 exec.cpp:78] Scheduling shutdown of the executor
I1026 03:29:53.453627 9285 exec.cpp:459] Slave exited ... shutting down
I1026 03:29:53.453691 9285 exec.cpp:476] Executor::shutdown took 1393ns
I1026 03:29:53.453721 9285 exec.cpp:78] Scheduling shutdown of the executor
Build step 'Execute shell' marked build as failure
Recording test results
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#2490
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2490/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#2489
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2489/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 27165 lines...]
I1026 06:41:46.457535 25758 recover.cpp:188] Received a recover response from a replica in STARTING status
I1026 06:41:46.457991 25750 recover.cpp:554] Updating replica status to VOTING
I1026 06:41:46.458199 25752 master.cpp:1242] The newly elected leader is master@67.195.81.187:43097 with id 20141026-064146-3142697795-43097-25733
I1026 06:41:46.458217 25752 master.cpp:1255] Elected as the leading master!
I1026 06:41:46.458232 25752 master.cpp:1073] Recovering from registrar
I1026 06:41:46.458415 25751 registrar.cpp:313] Recovering registrar
I1026 06:41:46.458650 25760 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 573727ns
I1026 06:41:46.458668 25760 replica.cpp:320] Persisted replica status to VOTING
I1026 06:41:46.458760 25749 recover.cpp:568] Successfully joined the Paxos group
I1026 06:41:46.459080 25749 recover.cpp:452] Recover process terminated
I1026 06:41:46.459432 25759 log.cpp:656] Attempting to start the writer
I1026 06:41:46.460531 25748 replica.cpp:474] Replica received implicit promise request with proposal 1
I1026 06:41:46.461136 25748 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 580573ns
I1026 06:41:46.461159 25748 replica.cpp:342] Persisted promised to 1
I1026 06:41:46.461663 25759 coordinator.cpp:230] Coordinator attemping to fill missing position
I1026 06:41:46.462652 25754 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I1026 06:41:46.462955 25754 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 277799ns
I1026 06:41:46.462973 25754 replica.cpp:676] Persisted action at 0
I1026 06:41:46.463737 25762 replica.cpp:508] Replica received write request for position 0
I1026 06:41:46.463778 25762 leveldb.cpp:438] Reading position from leveldb took 21076ns
I1026 06:41:46.464140 25762 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 338716ns
I1026 06:41:46.464161 25762 replica.cpp:676] Persisted action at 0
I1026 06:41:46.464614 25748 replica.cpp:655] Replica received learned notice for position 0
I1026 06:41:46.718680 25748 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 795695ns
I1026 06:41:46.718708 25748 replica.cpp:676] Persisted action at 0
I1026 06:41:46.718729 25748 replica.cpp:661] Replica learned NOP action at position 0
I1026 06:41:46.719110 25759 log.cpp:672] Writer started with ending position 0
I1026 06:41:46.720623 25749 leveldb.cpp:438] Reading position from leveldb took 28527ns
I1026 06:41:46.723644 25751 registrar.cpp:346] Successfully fetched the registry (0B) in 265.200896ms
I1026 06:41:46.723754 25751 registrar.cpp:445] Applied 1 operations in 17532ns; attempting to update the 'registry'
I1026 06:41:46.726586 25759 log.cpp:680] Attempting to append 139 bytes to the log
I1026 06:41:46.726748 25752 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I1026 06:41:46.727654 25755 replica.cpp:508] Replica received write request for position 1
I1026 06:41:46.728191 25755 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 508773ns
I1026 06:41:46.728211 25755 replica.cpp:676] Persisted action at 1
I1026 06:41:46.728724 25753 replica.cpp:655] Replica received learned notice for position 1
I1026 06:41:46.729320 25753 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 569027ns
I1026 06:41:46.729344 25753 replica.cpp:676] Persisted action at 1
I1026 06:41:46.729362 25753 replica.cpp:661] Replica learned APPEND action at position 1
I1026 06:41:46.730180 25748 registrar.cpp:490] Successfully updated the 'registry' in 6.381824ms
I1026 06:41:46.730314 25748 registrar.cpp:376] Successfully recovered registrar
I1026 06:41:46.730535 25748 log.cpp:699] Attempting to truncate the log to 1
I1026 06:41:46.730551 25753 master.cpp:1100] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register
I1026 06:41:46.730693 25751 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2
I1026 06:41:46.731369 25755 replica.cpp:508] Replica received write request for position 2
I1026 06:41:46.731914 25755 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 518190ns
I1026 06:41:46.731933 25755 replica.cpp:676] Persisted action at 2
I1026 06:41:46.732419 25747 replica.cpp:655] Replica received learned notice for position 2
I1026 06:41:46.732816 25747 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 370032ns
I1026 06:41:46.732863 25747 leveldb.cpp:401] Deleting ~1 keys from leveldb took 27192ns
I1026 06:41:46.732877 25747 replica.cpp:676] Persisted action at 2
I1026 06:41:46.732900 25747 replica.cpp:661] Replica learned TRUNCATE action at position 2
I1026 06:41:46.745275 25757 slave.cpp:169] Slave started on 97)@67.195.81.187:43097
I1026 06:41:46.745347 25757 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/credential'
I1026 06:41:46.745471 25757 slave.cpp:276] Slave using credential for: test-principal
I1026 06:41:46.745676 25757 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1026 06:41:46.745760 25757 slave.cpp:318] Slave hostname: pomona.apache.org
I1026 06:41:46.745774 25757 slave.cpp:319] Slave checkpoint: false
W1026 06:41:46.745779 25757 slave.cpp:321] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag
I1026 06:41:46.746373 25752 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/meta'
I1026 06:41:46.746532 25761 status_update_manager.cpp:197] Recovering status update manager
I1026 06:41:46.746846 25762 slave.cpp:3442] Finished recovery
I1026 06:41:46.747267 25751 status_update_manager.cpp:171] Pausing sending status updates
I1026 06:41:46.747267 25749 slave.cpp:602] New master detected at master@67.195.81.187:43097
I1026 06:41:46.747344 25749 slave.cpp:665] Authenticating with master master@67.195.81.187:43097
I1026 06:41:46.747414 25749 slave.cpp:638] Detecting new master
I1026 06:41:46.747441 25761 authenticatee.hpp:133] Creating new client SASL connection
I1026 06:41:46.747570 25761 master.cpp:3853] Authenticating slave(97)@67.195.81.187:43097
I1026 06:41:46.747745 25754 authenticator.hpp:161] Creating new server SASL connection
I1026 06:41:46.747874 25748 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5
I1026 06:41:46.747900 25748 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5'
I1026 06:41:46.747982 25757 authenticator.hpp:267] Received SASL authentication start
I1026 06:41:46.748023 25757 authenticator.hpp:389] Authentication requires more steps
I1026 06:41:46.748107 25758 authenticatee.hpp:270] Received SASL authentication step
I1026 06:41:46.748181 25757 authenticator.hpp:295] Received SASL authentication step
I1026 06:41:46.748204 25757 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1026 06:41:46.748214 25757 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I1026 06:41:46.748250 25757 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1026 06:41:46.748277 25757 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1026 06:41:46.748314 25757 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1026 06:41:46.748325 25757 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1026 06:41:46.748347 25757 authenticator.hpp:381] Authentication success
I1026 06:41:46.748428 25757 authenticatee.hpp:310] Authentication success
I1026 06:41:46.748456 25758 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(97)@67.195.81.187:43097
I1026 06:41:46.748574 25751 slave.cpp:722] Successfully authenticated with master master@67.195.81.187:43097
I1026 06:41:46.748672 25751 slave.cpp:1050] Will retry registration in 1.631964ms if necessary
I1026 06:41:46.748816 25757 master.cpp:3032] Registering slave at slave(97)@67.195.81.187:43097 (pomona.apache.org) with id 20141026-064146-3142697795-43097-25733-S0
I1026 06:41:46.749179 25760 registrar.cpp:445] Applied 1 operations in 56021ns; attempting to update the 'registry'
I1026 06:41:46.751027 25751 slave.cpp:1050] Will retry registration in 24.736363ms if necessary
I1026 06:41:46.751168 25752 master.cpp:3020] Ignoring register slave message from slave(97)@67.195.81.187:43097 (pomona.apache.org) as admission is already in progress
I1026 06:41:46.751997 25754 log.cpp:680] Attempting to append 316 bytes to the log
I1026 06:41:46.752126 25749 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3
I1026 06:41:46.752656 25757 replica.cpp:508] Replica received write request for position 3
I1026 06:41:46.752861 25757 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 184127ns
I1026 06:41:46.752876 25757 replica.cpp:676] Persisted action at 3
I1026 06:41:46.753203 25749 replica.cpp:655] Replica received learned notice for position 3
I1026 06:41:46.753525 25749 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 303871ns
I1026 06:41:46.753541 25749 replica.cpp:676] Persisted action at 3
I1026 06:41:46.753553 25749 replica.cpp:661] Replica learned APPEND action at position 3
I1026 06:41:46.754174 25756 registrar.cpp:490] Successfully updated the 'registry' in 4.947968ms
I1026 06:41:46.754426 25756 log.cpp:699] Attempting to truncate the log to 3
I1026 06:41:46.754539 25750 slave.cpp:2522] Received ping from slave-observer(99)@67.195.81.187:43097
I1026 06:41:46.754572 25754 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4
I1026 06:41:46.754669 25762 master.cpp:3086] Registered slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1026 06:41:46.754787 25755 slave.cpp:756] Registered with master master@67.195.81.187:43097; given slave ID 20141026-064146-3142697795-43097-25733-S0
I1026 06:41:46.754802 25756 hierarchical_allocator_process.hpp:442] Added slave 20141026-064146-3142697795-43097-25733-S0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I1026 06:41:46.755051 25751 replica.cpp:508] Replica received write request for position 4
I1026 06:41:46.758406 25733 sched.cpp:137] Version: 0.21.0
I1026 06:41:46.870838 25755 status_update_manager.cpp:178] Resuming sending status updates
I1026 06:41:46.870841 25756 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141026-064146-3142697795-43097-25733-S0 in 52614ns
I1026 06:41:46.871240 25755 sched.cpp:233] New master detected at master@67.195.81.187:43097
I1026 06:41:46.871265 25755 sched.cpp:283] Authenticating with master master@67.195.81.187:43097
I1026 06:41:46.871430 25750 authenticatee.hpp:133] Creating new client SASL connection
I1026 06:41:46.871518 25751 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 669981ns
I1026 06:41:46.871548 25751 replica.cpp:676] Persisted action at 4
I1026 06:41:46.871724 25761 master.cpp:3853] Authenticating scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:46.871932 25762 authenticator.hpp:161] Creating new server SASL connection
I1026 06:41:46.872071 25757 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5
I1026 06:41:46.872081 25762 replica.cpp:655] Replica received learned notice for position 4
I1026 06:41:46.872092 25757 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5'
I1026 06:41:46.872167 25757 authenticator.hpp:267] Received SASL authentication start
I1026 06:41:46.872228 25757 authenticator.hpp:389] Authentication requires more steps
I1026 06:41:46.872275 25757 authenticatee.hpp:270] Received SASL authentication step
I1026 06:41:46.872398 25757 authenticator.hpp:295] Received SASL authentication step
I1026 06:41:46.872414 25757 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1026 06:41:46.872422 25757 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I1026 06:41:46.872462 25757 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1026 06:41:46.872475 25757 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1026 06:41:46.872481 25757 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1026 06:41:46.872485 25757 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1026 06:41:46.872494 25757 authenticator.hpp:381] Authentication success
I1026 06:41:46.872555 25758 authenticatee.hpp:310] Authentication success
I1026 06:41:46.872586 25754 master.cpp:3893] Successfully authenticated principal 'test-principal' at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:46.872618 25762 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 515681ns
I1026 06:41:46.872690 25762 leveldb.cpp:401] Deleting ~2 keys from leveldb took 47776ns
I1026 06:41:46.872711 25762 replica.cpp:676] Persisted action at 4
I1026 06:41:46.872716 25751 sched.cpp:357] Successfully authenticated with master master@67.195.81.187:43097
I1026 06:41:46.872735 25751 sched.cpp:476] Sending registration request to master@67.195.81.187:43097
I1026 06:41:46.872733 25762 replica.cpp:661] Replica learned TRUNCATE action at position 4
I1026 06:41:46.872853 25756 master.cpp:1362] Received registration request for framework 'default' at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:46.872918 25756 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1026 06:41:46.873502 25760 master.cpp:1426] Registering framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:46.873715 25750 hierarchical_allocator_process.hpp:329] Added framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.873859 25749 sched.cpp:407] Framework registered with 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.873858 25750 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141026-064146-3142697795-43097-25733-S0 to framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.873914 25749 sched.cpp:421] Scheduler::registered took 37389ns
I1026 06:41:46.874214 25750 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 476028ns
I1026 06:41:46.874563 25748 master.cpp:3795] Sending 1 offers to framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:46.875187 25753 sched.cpp:544] Scheduler::resourceOffers took 312078ns
I1026 06:41:46.875845 25748 master.cpp:2321] Processing reply for offers: [ 20141026-064146-3142697795-43097-25733-O0 ] on slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org) for framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
W1026 06:41:46.875929 25748 master.cpp:1969] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1026 06:41:46.875949 25748 master.cpp:1980] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1026 06:41:46.876027 25748 master.cpp:2417] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins'
I1026 06:41:46.876816 25748 master.hpp:877] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141026-064146-3142697795-43097-25733-S0 (pomona.apache.org)
I1026 06:41:46.876862 25748 master.cpp:2480] Launching task 0 of framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097 with resources cpus(*):2; mem(*):1024 on slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:46.877115 25750 slave.cpp:1081] Got assigned task 0 for framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.877193 25748 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141026-064146-3142697795-43097-25733-S0 from framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.877224 25748 hierarchical_allocator_process.hpp:599] Framework 20141026-064146-3142697795-43097-25733-0000 filtered slave 20141026-064146-3142697795-43097-25733-S0 for 5secs
I1026 06:41:46.877611 25750 slave.cpp:1191] Launching task 0 for framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:46.880142 25750 slave.cpp:3857] Launching executor default of framework 20141026-064146-3142697795-43097-25733-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/slaves/20141026-064146-3142697795-43097-25733-S0/frameworks/20141026-064146-3142697795-43097-25733-0000/executors/default/runs/58a24d23-fcee-4ccd-9b55-b166de2edab4'
I1026 06:41:46.882810 25750 exec.cpp:132] Version: 0.21.0
I1026 06:41:46.883015 25758 exec.cpp:182] Executor started at: executor(43)@67.195.81.187:43097 with pid 25733
I1026 06:41:46.883136 25750 slave.cpp:1317] Queuing task '0' for executor default of framework '20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.017436 25750 slave.cpp:555] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/slaves/20141026-064146-3142697795-43097-25733-S0/frameworks/20141026-064146-3142697795-43097-25733-0000/executors/default/runs/58a24d23-fcee-4ccd-9b55-b166de2edab4'
I1026 06:41:47.017570 25750 slave.cpp:2788] Monitoring executor 'default' of framework '20141026-064146-3142697795-43097-25733-0000' in container '58a24d23-fcee-4ccd-9b55-b166de2edab4'
I1026 06:41:47.017729 25750 slave.cpp:1849] Got registration for executor 'default' of framework 20141026-064146-3142697795-43097-25733-0000 from executor(43)@67.195.81.187:43097
I1026 06:41:47.018019 25750 slave.cpp:1968] Flushing queued task 0 for executor 'default' of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.018118 25751 exec.cpp:206] Executor registered on slave 20141026-064146-3142697795-43097-25733-S0
I1026 06:41:47.019531 25751 exec.cpp:218] Executor::registered took 19682ns
I1026 06:41:47.019644 25751 exec.cpp:293] Executor asked to run task '0'
I1026 06:41:47.019692 25751 exec.cpp:302] Executor::launchTask took 36998ns
I1026 06:41:47.020967 25751 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.021155 25751 slave.cpp:2202] Handling status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 from executor(43)@67.195.81.187:43097
I1026 06:41:47.021420 25753 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.021450 25753 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.021644 25753 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to the slave
I1026 06:41:47.021826 25757 slave.cpp:2442] Forwarding the update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to master@67.195.81.187:43097
I1026 06:41:47.021947 25757 slave.cpp:2369] Status update manager successfully handled status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.021970 25757 slave.cpp:2375] Sending acknowledgement for status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to executor(43)@67.195.81.187:43097
I1026 06:41:47.022037 25748 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.022085 25754 exec.cpp:339] Executor received status update acknowledgement 4044d259-9bee-4958-b63a-8e22c1fd5772 for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.022125 25748 master.cpp:3382] Status update TASK_RUNNING (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 from slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:47.022162 25748 master.cpp:4617] Updating the latest state of task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to TASK_RUNNING
I1026 06:41:47.022233 25762 sched.cpp:635] Scheduler::statusUpdate took 35780ns
I1026 06:41:47.022446 25750 master.cpp:2882] Forwarding status update acknowledgement 4044d259-9bee-4958-b63a-8e22c1fd5772 for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097 to slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:47.022650 25756 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.022794 25755 slave.cpp:2846] Executor 'default' of framework 20141026-064146-3142697795-43097-25733-0000 exited with status 0
I1026 06:41:47.025022 25755 slave.cpp:2202] Handling status update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 from @0.0.0.0:0
I1026 06:41:47.025094 25755 slave.cpp:4165] Terminating task 0
I1026 06:41:47.025454 25755 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 4044d259-9bee-4958-b63a-8e22c1fd5772) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.025532 25749 master.cpp:3462] Executor default of framework 20141026-064146-3142697795-43097-25733-0000 on slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org) exited with status 0
I1026 06:41:47.025600 25749 master.cpp:4705] Removing executor 'default' with resources of framework 20141026-064146-3142697795-43097-25733-0000 on slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:47.025724 25754 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.025782 25754 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to the slave
I1026 06:41:47.026013 25751 slave.cpp:2442] Forwarding the update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to master@67.195.81.187:43097
I1026 06:41:47.026139 25751 slave.cpp:2369] Status update manager successfully handled status update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.026262 25748 master.cpp:3410] Forwarding status update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.026356 25748 master.cpp:3382] Status update TASK_LOST (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 from slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:47.026384 25748 master.cpp:4617] Updating the latest state of task 0 of framework 20141026-064146-3142697795-43097-25733-0000 to TASK_LOST
I1026 06:41:47.026463 25762 sched.cpp:635] Scheduler::statusUpdate took 17045ns
I1026 06:41:47.026646 25748 master.cpp:4676] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141026-064146-3142697795-43097-25733-0000 on slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:47.026696 25749 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141026-064146-3142697795-43097-25733-S0 from framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:47.026762 25748 master.cpp:2882] Forwarding status update acknowledgement 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32 for task 0 of framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097 to slave 20141026-064146-3142697795-43097-25733-S0 at slave(97)@67.195.81.187:43097 (pomona.apache.org)
I1026 06:41:49.050568 25752 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.050704 25752 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.050910 25751 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 6236c6e5-6c17-4ba1-a5b0-02dc3db33c32) for task 0 of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.050966 25751 slave.cpp:4204] Completing task 0
I1026 06:41:49.050999 25751 slave.cpp:2983] Cleaning up executor 'default' of framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.051177 25757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/slaves/20141026-064146-3142697795-43097-25733-S0/frameworks/20141026-064146-3142697795-43097-25733-0000/executors/default/runs/58a24d23-fcee-4ccd-9b55-b166de2edab4' for gc 1.00000326966095weeks in the future
I1026 06:41:49.051319 25751 slave.cpp:3060] Cleaning up framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.051378 25752 status_update_manager.cpp:279] Closing status update streams for framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.051398 25757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/slaves/20141026-064146-3142697795-43097-25733-S0/frameworks/20141026-064146-3142697795-43097-25733-0000/executors/default' for gc 1.00000326966095weeks in the future
I1026 06:41:49.051498 25757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_ht3BFs/slaves/20141026-064146-3142697795-43097-25733-S0/frameworks/20141026-064146-3142697795-43097-25733-0000' for gc 1.00000326966095weeks in the future
I1026 06:41:49.067361 25758 slave.cpp:3297] Current usage 90.00%. Max allowed age: 0ns
I1026 06:41:49.067641 25749 hierarchical_allocator_process.hpp:734] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20141026-064146-3142697795-43097-25733-S0 to framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.067864 25749 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 405014ns
I1026 06:41:49.068156 25756 master.cpp:3795] Sending 1 offers to framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:49.068528 25749 sched.cpp:544] Scheduler::resourceOffers took 21762ns
../../src/tests/gc_tests.cpp:675: Failure
Value of: os::exists(executorDir)
Actual: true
Expected: false
I1026 06:41:49.087713 25757 master.cpp:768] Framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097 disconnected
I1026 06:41:49.087738 25757 master.cpp:1731] Disconnecting framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:49.087756 25757 master.cpp:1747] Deactivating framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:49.087870 25762 hierarchical_allocator_process.hpp:405] Deactivated framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.088148 25757 master.cpp:790] Giving framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097 0ns to failover
I1026 06:41:49.088464 25761 master.cpp:3665] Framework failover timeout, removing framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:49.088486 25761 master.cpp:4201] Removing framework 20141026-064146-3142697795-43097-25733-0000 (default) at scheduler-0bb4a588-c204-4a22-b57d-c2cfa5f3760c@67.195.81.187:43097
I1026 06:41:49.088531 25751 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141026-064146-3142697795-43097-25733-S0 from framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.088618 25748 slave.cpp:1522] Asked to shut down framework 20141026-064146-3142697795-43097-25733-0000 by master@67.195.81.187:43097
W1026 06:41:49.088646 25748 slave.cpp:1537] Cannot shut down unknown framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.088754 25761 master.cpp:677] Master terminating
I1026 06:41:49.088845 25755 hierarchical_allocator_process.hpp:360] Removed framework 20141026-064146-3142697795-43097-25733-0000
I1026 06:41:49.089078 25751 slave.cpp:2607] master@67.195.81.187:43097 exited
W1026 06:41:49.089097 25751 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected
*** Aborted at 1414305709 (unix time) try "date -d @1414305709" if you are using GNU date ***
PC: @ 0x1 (unknown)
make[3]: *** [check-local] Segmentation fault
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#2488
Posted by Benjamin Mahler <be...@gmail.com>.
+Cody
Hm.. this test failure used to dump a pure virtual stack trace (see below
for example). Did we lose that cody? Any clue why there's no stacktrace now
for the segfault?
F0910 19:26:51.395694 16920 logging.cpp:57] RAW: Pure virtual method called
@ 0x2ba2cbfc59bd google::LogMessage::Fail()
@ 0x2ba2cbfcd0b6 google::RawLog__()
@ 0x505826
mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0x79d2f4
mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0x98c153
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x9832f0 testing::Test::Run()
@ 0x9833ce testing::TestInfo::Run()
@ 0x9834d5 testing::TestCase::Run()
@ 0x983778 testing::internal::UnitTestImpl::RunAllTests()
@ 0x983a17 testing::UnitTest::Run()
@ 0x491cb3 main
@ 0x2ba2cdce4ec5 (unknown)
@ 0x4a02c8 (unknown)
On Sat, Oct 25, 2014 at 10:38 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2488/changes
> >
>
> Changes:
>
> [yujie.jay] PortMappingIsolator: Swap TX/RX in statistic collection in
> usage
>
> ------------------------------------------
> [...truncated 21400 lines...]
> I1026 05:37:40.692209 1944 master.cpp:1242] The newly elected leader is
> master@67.195.81.187:48044 with id 20141026-053740-3142697795-48044-1919
> I1026 05:37:40.692231 1944 master.cpp:1255] Elected as the leading master!
> I1026 05:37:40.692258 1944 master.cpp:1073] Recovering from registrar
> I1026 05:37:40.692318 1938 recover.cpp:188] Received a recover response
> from a replica in STARTING status
> I1026 05:37:40.692419 1947 registrar.cpp:313] Recovering registrar
> I1026 05:37:40.692847 1936 recover.cpp:554] Updating replica status to
> VOTING
> I1026 05:37:40.717001 1939 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 763823ns
> I1026 05:37:40.717027 1939 replica.cpp:320] Persisted replica status to
> VOTING
> I1026 05:37:40.717118 1948 recover.cpp:568] Successfully joined the Paxos
> group
> I1026 05:37:40.717316 1948 recover.cpp:452] Recover process terminated
> I1026 05:37:40.717768 1934 log.cpp:656] Attempting to start the writer
> I1026 05:37:40.719187 1935 replica.cpp:474] Replica received implicit
> promise request with proposal 1
> I1026 05:37:40.719761 1935 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 553560ns
> I1026 05:37:40.719779 1935 replica.cpp:342] Persisted promised to 1
> I1026 05:37:40.720424 1945 coordinator.cpp:230] Coordinator attemping to
> fill missing position
> I1026 05:37:40.721951 1933 replica.cpp:375] Replica received explicit
> promise request for position 0 with proposal 2
> I1026 05:37:40.722365 1933 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 385625ns
> I1026 05:37:40.722385 1933 replica.cpp:676] Persisted action at 0
> I1026 05:37:40.723311 1939 replica.cpp:508] Replica received write
> request for position 0
> I1026 05:37:40.723372 1939 leveldb.cpp:438] Reading position from leveldb
> took 35296ns
> I1026 05:37:40.723793 1939 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 387051ns
> I1026 05:37:40.723814 1939 replica.cpp:676] Persisted action at 0
> I1026 05:37:40.724311 1942 replica.cpp:655] Replica received learned
> notice for position 0
> I1026 05:37:40.724762 1942 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 424112ns
> I1026 05:37:40.724787 1942 replica.cpp:676] Persisted action at 0
> I1026 05:37:40.724809 1942 replica.cpp:661] Replica learned NOP action at
> position 0
> I1026 05:37:40.725275 1936 log.cpp:672] Writer started with ending
> position 0
> I1026 05:37:40.726511 1945 leveldb.cpp:438] Reading position from leveldb
> took 33201ns
> I1026 05:37:40.729558 1947 registrar.cpp:346] Successfully fetched the
> registry (0B) in 37.101056ms
> I1026 05:37:40.729665 1947 registrar.cpp:445] Applied 1 operations in
> 19075ns; attempting to update the 'registry'
> I1026 05:37:40.732529 1937 log.cpp:680] Attempting to append 138 bytes to
> the log
> I1026 05:37:40.732743 1943 coordinator.cpp:340] Coordinator attempting to
> write APPEND action at position 1
> I1026 05:37:40.733597 1940 replica.cpp:508] Replica received write
> request for position 1
> I1026 05:37:40.734328 1940 leveldb.cpp:343] Persisting action (157 bytes)
> to leveldb took 702340ns
> I1026 05:37:40.734354 1940 replica.cpp:676] Persisted action at 1
> I1026 05:37:40.734871 1939 replica.cpp:655] Replica received learned
> notice for position 1
> I1026 05:37:40.735640 1939 leveldb.cpp:343] Persisting action (159 bytes)
> to leveldb took 743999ns
> I1026 05:37:40.735673 1939 replica.cpp:676] Persisted action at 1
> I1026 05:37:40.735704 1939 replica.cpp:661] Replica learned APPEND action
> at position 1
> I1026 05:37:40.736652 1940 registrar.cpp:490] Successfully updated the
> 'registry' in 6.947072ms
> I1026 05:37:40.736780 1940 registrar.cpp:376] Successfully recovered
> registrar
> I1026 05:37:40.737062 1936 master.cpp:1100] Recovered 0 slaves from the
> Registry (100B) ; allowing 10mins for slaves to re-register
> I1026 05:37:40.737082 1940 log.cpp:699] Attempting to truncate the log to
> 1
> I1026 05:37:40.737210 1934 coordinator.cpp:340] Coordinator attempting to
> write TRUNCATE action at position 2
> I1026 05:37:40.737901 1941 replica.cpp:508] Replica received write
> request for position 2
> I1026 05:37:40.738412 1941 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 484781ns
> I1026 05:37:40.738432 1941 replica.cpp:676] Persisted action at 2
> I1026 05:37:40.738870 1941 replica.cpp:655] Replica received learned
> notice for position 2
> I1026 05:37:40.739341 1941 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 451900ns
> I1026 05:37:40.739384 1941 leveldb.cpp:401] Deleting ~1 keys from leveldb
> took 28203ns
> I1026 05:37:40.739397 1941 replica.cpp:676] Persisted action at 2
> I1026 05:37:40.739411 1941 replica.cpp:661] Replica learned TRUNCATE
> action at position 2
> I1026 05:37:40.751147 1935 slave.cpp:169] Slave started on 71)@
> 67.195.81.187:48044
> I1026 05:37:40.751179 1935 credentials.hpp:84] Loading credential for
> authentication from
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/credential'
> I1026 05:37:40.751363 1935 slave.cpp:276] Slave using credential for:
> test-principal
> I1026 05:37:40.751664 1935 slave.cpp:289] Slave resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I1026 05:37:40.751751 1935 slave.cpp:318] Slave hostname:
> pomona.apache.org
> I1026 05:37:40.751765 1935 slave.cpp:319] Slave checkpoint: false
> W1026 05:37:40.751775 1935 slave.cpp:321] Disabling checkpointing is
> deprecated and the --checkpoint flag will be removed in a future release.
> Please avoid using this flag
> I1026 05:37:40.752568 1945 state.cpp:33] Recovering state from
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/meta'
> I1026 05:37:40.752869 1945 status_update_manager.cpp:197] Recovering
> status update manager
> I1026 05:37:40.753314 1940 slave.cpp:3442] Finished recovery
> I1026 05:37:40.753865 1935 slave.cpp:602] New master detected at
> master@67.195.81.187:48044
> I1026 05:37:40.753885 1935 slave.cpp:665] Authenticating with master
> master@67.195.81.187:48044
> I1026 05:37:40.753962 1935 slave.cpp:638] Detecting new master
> I1026 05:37:40.753981 1943 status_update_manager.cpp:171] Pausing sending
> status updates
> I1026 05:37:40.754030 1939 authenticatee.hpp:133] Creating new client
> SASL connection
> I1026 05:37:40.754212 1944 master.cpp:3853] Authenticating slave(71)@
> 67.195.81.187:48044
> I1026 05:37:40.754413 1937 authenticator.hpp:161] Creating new server
> SASL connection
> I1026 05:37:40.754632 1948 authenticatee.hpp:224] Received SASL
> authentication mechanisms: CRAM-MD5
> I1026 05:37:40.754653 1948 authenticatee.hpp:250] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1026 05:37:40.754703 1948 authenticator.hpp:267] Received SASL
> authentication start
> I1026 05:37:40.754739 1948 authenticator.hpp:389] Authentication requires
> more steps
> I1026 05:37:40.754792 1948 authenticatee.hpp:270] Received SASL
> authentication step
> I1026 05:37:40.754848 1948 authenticator.hpp:295] Received SASL
> authentication step
> I1026 05:37:40.754866 1948 auxprop.cpp:81] Request to lookup properties
> for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: '
> pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1026 05:37:40.754874 1948 auxprop.cpp:153] Looking up auxiliary property
> '*userPassword'
> I1026 05:37:40.754927 1948 auxprop.cpp:153] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1026 05:37:40.754945 1948 auxprop.cpp:81] Request to lookup properties
> for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: '
> pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1026 05:37:40.754951 1948 auxprop.cpp:103] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1026 05:37:40.754956 1948 auxprop.cpp:103] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1026 05:37:40.754966 1948 authenticator.hpp:381] Authentication success
> I1026 05:37:40.755040 1948 authenticatee.hpp:310] Authentication success
> I1026 05:37:40.755082 1936 master.cpp:3893] Successfully authenticated
> principal 'test-principal' at slave(71)@67.195.81.187:48044
> I1026 05:37:40.755187 1937 slave.cpp:722] Successfully authenticated with
> master master@67.195.81.187:48044
> I1026 05:37:40.755321 1937 slave.cpp:1050] Will retry registration in
> 8.911789ms if necessary
> I1026 05:37:40.755475 1945 master.cpp:3032] Registering slave at
> slave(71)@67.195.81.187:48044 (pomona.apache.org) with id
> 20141026-053740-3142697795-48044-1919-S0
> I1026 05:37:40.755848 1945 registrar.cpp:445] Applied 1 operations in
> 47887ns; attempting to update the 'registry'
> I1026 05:37:40.758046 1937 log.cpp:680] Attempting to append 314 bytes to
> the log
> I1026 05:37:40.764782 1933 slave.cpp:1050] Will retry registration in
> 17.226691ms if necessary
> I1026 05:37:40.764904 1944 master.cpp:3020] Ignoring register slave
> message from slave(71)@67.195.81.187:48044 (pomona.apache.org) as
> admission is already in progress
> I1026 05:37:40.820008 1938 coordinator.cpp:340] Coordinator attempting to
> write APPEND action at position 3
> I1026 05:37:40.820843 1941 replica.cpp:508] Replica received write
> request for position 3
> I1026 05:37:40.821717 1941 leveldb.cpp:343] Persisting action (333 bytes)
> to leveldb took 846259ns
> I1026 05:37:40.821735 1941 replica.cpp:676] Persisted action at 3
> I1026 05:37:40.822347 1934 replica.cpp:655] Replica received learned
> notice for position 3
> I1026 05:37:40.823001 1934 leveldb.cpp:343] Persisting action (335 bytes)
> to leveldb took 628489ns
> I1026 05:37:40.823026 1934 replica.cpp:676] Persisted action at 3
> I1026 05:37:40.823048 1934 replica.cpp:661] Replica learned APPEND action
> at position 3
> I1026 05:37:40.824146 1935 registrar.cpp:490] Successfully updated the
> 'registry' in 68.205056ms
> I1026 05:37:40.824504 1936 log.cpp:699] Attempting to truncate the log to
> 3
> I1026 05:37:40.824625 1935 coordinator.cpp:340] Coordinator attempting to
> write TRUNCATE action at position 4
> I1026 05:37:40.824702 1936 slave.cpp:2522] Received ping from
> slave-observer(56)@67.195.81.187:48044
> I1026 05:37:40.824784 1942 master.cpp:3086] Registered slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1026 05:37:40.824972 1936 slave.cpp:756] Registered with master
> master@67.195.81.187:48044; given slave ID
> 20141026-053740-3142697795-48044-1919-S0
> I1026 05:37:40.825006 1948 hierarchical_allocator_process.hpp:442] Added
> slave 20141026-053740-3142697795-48044-1919-S0 (pomona.apache.org) with
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
> I1026 05:37:40.825063 1941 status_update_manager.cpp:178] Resuming
> sending status updates
> I1026 05:37:40.825163 1948 hierarchical_allocator_process.hpp:679]
> Performed allocation for slave 20141026-053740-3142697795-48044-1919-S0 in
> 57286ns
> I1026 05:37:40.825516 1943 replica.cpp:508] Replica received write
> request for position 4
> I1026 05:37:40.826031 1943 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 487705ns
> I1026 05:37:40.826053 1943 replica.cpp:676] Persisted action at 4
> I1026 05:37:40.826514 1941 replica.cpp:655] Replica received learned
> notice for position 4
> I1026 05:37:40.826992 1941 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 457616ns
> I1026 05:37:40.827054 1941 leveldb.cpp:401] Deleting ~2 keys from leveldb
> took 41154ns
> I1026 05:37:40.827070 1941 replica.cpp:676] Persisted action at 4
> I1026 05:37:40.827090 1941 replica.cpp:661] Replica learned TRUNCATE
> action at position 4
> I1026 05:37:40.829262 1919 sched.cpp:137] Version: 0.21.0
> I1026 05:37:40.829761 1939 sched.cpp:233] New master detected at
> master@67.195.81.187:48044
> I1026 05:37:40.829787 1939 sched.cpp:283] Authenticating with master
> master@67.195.81.187:48044
> I1026 05:37:40.829941 1936 authenticatee.hpp:133] Creating new client
> SASL connection
> I1026 05:37:40.830123 1943 master.cpp:3853] Authenticating
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:40.830319 1938 authenticator.hpp:161] Creating new server
> SASL connection
> I1026 05:37:40.830438 1942 authenticatee.hpp:224] Received SASL
> authentication mechanisms: CRAM-MD5
> I1026 05:37:40.830461 1942 authenticatee.hpp:250] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1026 05:37:40.830538 1946 authenticator.hpp:267] Received SASL
> authentication start
> I1026 05:37:40.830588 1946 authenticator.hpp:389] Authentication requires
> more steps
> I1026 05:37:40.830651 1946 authenticatee.hpp:270] Received SASL
> authentication step
> I1026 05:37:40.830736 1936 authenticator.hpp:295] Received SASL
> authentication step
> I1026 05:37:40.830762 1936 auxprop.cpp:81] Request to lookup properties
> for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: '
> pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1026 05:37:40.830773 1936 auxprop.cpp:153] Looking up auxiliary property
> '*userPassword'
> I1026 05:37:40.830808 1936 auxprop.cpp:153] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1026 05:37:40.830829 1936 auxprop.cpp:81] Request to lookup properties
> for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: '
> pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1026 05:37:40.830838 1936 auxprop.cpp:103] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1026 05:37:40.830844 1936 auxprop.cpp:103] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1026 05:37:40.830858 1936 authenticator.hpp:381] Authentication success
> I1026 05:37:40.830924 1942 authenticatee.hpp:310] Authentication success
> I1026 05:37:40.830951 1934 master.cpp:3893] Successfully authenticated
> principal 'test-principal' at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:40.831087 1948 sched.cpp:357] Successfully authenticated with
> master master@67.195.81.187:48044
> I1026 05:37:40.831109 1948 sched.cpp:476] Sending registration request to
> master@67.195.81.187:48044
> I1026 05:37:40.831220 1946 master.cpp:1362] Received registration request
> for framework 'default' at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:40.831306 1946 master.cpp:1321] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1026 05:37:40.831666 1946 master.cpp:1426] Registering framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:40.831886 1948 hierarchical_allocator_process.hpp:329] Added
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.831992 1943 sched.cpp:407] Framework registered with
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.832056 1943 sched.cpp:421] Scheduler::registered took
> 39567ns
> I1026 05:37:40.832061 1948 hierarchical_allocator_process.hpp:734]
> Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> slave 20141026-053740-3142697795-48044-1919-S0 to framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.832465 1948 hierarchical_allocator_process.hpp:659]
> Performed allocation for 1 slaves in 557029ns
> I1026 05:37:40.832742 1947 master.cpp:3795] Sending 1 offers to framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:40.833416 1938 sched.cpp:544] Scheduler::resourceOffers took
> 413267ns
> I1026 05:37:40.834154 1944 master.cpp:2321] Processing reply for offers:
> [ 20141026-053740-3142697795-48044-1919-O0 ] on slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org) for framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> W1026 05:37:40.834249 1944 master.cpp:1969] Executor default for task 0
> uses less CPUs (None) than the minimum required (0.01). Please update your
> executor, as this will be mandatory in future releases.
> W1026 05:37:40.834276 1944 master.cpp:1980] Executor default for task 0
> uses less memory (None) than the minimum required (32MB). Please update
> your executor, as this will be mandatory in future releases.
> I1026 05:37:40.834388 1944 master.cpp:2417] Authorizing framework
> principal 'test-principal' to launch task 0 as user 'jenkins'
> I1026 05:37:40.835225 1935 master.hpp:877] Adding task 0 with resources
> cpus(*):2; mem(*):1024 on slave 20141026-053740-3142697795-48044-1919-S0 (
> pomona.apache.org)
> I1026 05:37:40.835311 1935 master.cpp:2480] Launching task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 with
> resources cpus(*):2; mem(*):1024 on slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org)
> I1026 05:37:40.985780 1940 slave.cpp:1081] Got assigned task 0 for
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.986291 1941 hierarchical_allocator_process.hpp:563]
> Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable:
> disk(*):1024; ports(*):[31000-32000]) on slave
> 20141026-053740-3142697795-48044-1919-S0 from framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.986400 1941 hierarchical_allocator_process.hpp:599]
> Framework 20141026-053740-3142697795-48044-1919-0000 filtered slave
> 20141026-053740-3142697795-48044-1919-S0 for 5secs
> I1026 05:37:40.986466 1940 slave.cpp:1191] Launching task 0 for framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.989169 1940 slave.cpp:3857] Launching executor default of
> framework 20141026-053740-3142697795-48044-1919-0000 in work directory
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91'
> I1026 05:37:40.991880 1940 exec.cpp:132] Version: 0.21.0
> I1026 05:37:40.992161 1939 exec.cpp:182] Executor started at:
> executor(12)@67.195.81.187:48044 with pid 1919
> I1026 05:37:40.992316 1940 slave.cpp:1317] Queuing task '0' for executor
> default of framework '20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.992468 1940 slave.cpp:555] Successfully attached file
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91'
> I1026 05:37:40.992540 1940 slave.cpp:1849] Got registration for executor
> 'default' of framework 20141026-053740-3142697795-48044-1919-0000 from
> executor(12)@67.195.81.187:48044
> I1026 05:37:40.992796 1940 slave.cpp:1968] Flushing queued task 0 for
> executor 'default' of framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.992856 1933 exec.cpp:206] Executor registered on slave
> 20141026-053740-3142697795-48044-1919-S0
> I1026 05:37:40.992960 1940 slave.cpp:2788] Monitoring executor 'default'
> of framework '20141026-053740-3142697795-48044-1919-0000' in container
> '51de1be1-00e4-45be-ba30-b017c1067a91'
> I1026 05:37:40.994954 1933 exec.cpp:218] Executor::registered took 26527ns
> I1026 05:37:40.995110 1933 exec.cpp:293] Executor asked to run task '0'
> I1026 05:37:40.995173 1933 exec.cpp:302] Executor::launchTask took 47056ns
> I1026 05:37:40.997432 1933 exec.cpp:525] Executor sending status update
> TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.997653 1933 slave.cpp:2202] Handling status update
> TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 from executor(12)@
> 67.195.81.187:48044
> I1026 05:37:40.997896 1947 status_update_manager.cpp:317] Received status
> update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0
> of framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.997936 1947 status_update_manager.cpp:494] Creating
> StatusUpdate stream for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.998147 1947 status_update_manager.cpp:371] Forwarding
> update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0
> of framework 20141026-053740-3142697795-48044-1919-0000 to the slave
> I1026 05:37:40.998363 1934 slave.cpp:2442] Forwarding the update
> TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 to
> master@67.195.81.187:48044
> I1026 05:37:40.998481 1934 slave.cpp:2369] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.998504 1934 slave.cpp:2375] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for
> task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to
> executor(12)@67.195.81.187:48044
> I1026 05:37:40.998644 1940 exec.cpp:339] Executor received status update
> acknowledgement 26f54ad4-b9bc-4d89-894e-f29a708f5a4d for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.998677 1944 master.cpp:3410] Forwarding status update
> TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.998766 1944 master.cpp:3382] Status update TASK_RUNNING
> (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000 from slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org)
> I1026 05:37:40.998797 1944 master.cpp:4617] Updating the latest state of
> task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to
> TASK_RUNNING
> I1026 05:37:40.998863 1948 sched.cpp:635] Scheduler::statusUpdate took
> 41522ns
> I1026 05:37:40.999078 1938 master.cpp:2882] Forwarding status update
> acknowledgement 26f54ad4-b9bc-4d89-894e-f29a708f5a4d for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 to
> slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@
> 67.195.81.187:48044 (pomona.apache.org)
> I1026 05:37:40.999328 1938 status_update_manager.cpp:389] Received status
> update acknowledgement (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for
> task 0 of framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:40.999362 1941 slave.cpp:2846] Executor 'default' of
> framework 20141026-053740-3142697795-48044-1919-0000 exited with status 0
> I1026 05:37:41.001579 1941 slave.cpp:2202] Handling status update
> TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 from @0.0.0.0:0
> I1026 05:37:41.001646 1941 slave.cpp:4165] Terminating task 0
> I1026 05:37:41.001994 1941 slave.cpp:1789] Status update manager
> successfully handled status update acknowledgement (UUID:
> 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:41.002087 1944 master.cpp:3462] Executor default of framework
> 20141026-053740-3142697795-48044-1919-0000 on slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org) exited with status 0
> I1026 05:37:41.002166 1944 master.cpp:4705] Removing executor 'default'
> with resources of framework 20141026-053740-3142697795-48044-1919-0000 on
> slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@
> 67.195.81.187:48044 (pomona.apache.org)
> I1026 05:37:41.002255 1933 status_update_manager.cpp:317] Received status
> update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:41.002393 1933 status_update_manager.cpp:371] Forwarding
> update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 to the slave
> I1026 05:37:41.002594 1935 slave.cpp:2442] Forwarding the update
> TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 to
> master@67.195.81.187:48044
> I1026 05:37:41.002760 1935 slave.cpp:2369] Status update manager
> successfully handled status update TASK_LOST (UUID:
> d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:41.002810 1934 master.cpp:3410] Forwarding status update
> TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.157433 1934 master.cpp:3382] Status update TASK_LOST
> (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000 from slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org)
> I1026 05:37:43.157491 1934 master.cpp:4617] Updating the latest state of
> task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to TASK_LOST
> I1026 05:37:43.157644 1940 sched.cpp:635] Scheduler::statusUpdate took
> 20710ns
> I1026 05:37:43.157738 1946 hierarchical_allocator_process.hpp:563]
> Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024;
> ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
> 20141026-053740-3142697795-48044-1919-S0 from framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.157824 1940 master.cpp:4676] Removing task 0 with
> resources cpus(*):2; mem(*):1024 of framework
> 20141026-053740-3142697795-48044-1919-0000 on slave
> 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044
> (pomona.apache.org)
> I1026 05:37:43.157938 1940 master.cpp:2882] Forwarding status update
> acknowledgement d749f825-9535-42e9-aac0-a5384af9269f for task 0 of
> framework 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 to
> slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@
> 67.195.81.187:48044 (pomona.apache.org)
> I1026 05:37:43.158191 1948 status_update_manager.cpp:389] Received status
> update acknowledgement (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for
> task 0 of framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.158341 1948 status_update_manager.cpp:525] Cleaning up
> status update stream for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.158565 1941 slave.cpp:1789] Status update manager
> successfully handled status update acknowledgement (UUID:
> d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.158627 1941 slave.cpp:4204] Completing task 0
> I1026 05:37:43.158670 1941 slave.cpp:2983] Cleaning up executor 'default'
> of framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.158879 1947 gc.cpp:56] Scheduling
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91'
> for gc 1.00000330839619weeks in the future
> I1026 05:37:43.159008 1941 slave.cpp:3060] Cleaning up framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.159097 1947 gc.cpp:56] Scheduling
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default'
> for gc 1.00000330839619weeks in the future
> I1026 05:37:43.159159 1941 status_update_manager.cpp:279] Closing status
> update streams for framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.159184 1947 gc.cpp:56] Scheduling
> '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000'
> for gc 1.00000330839619weeks in the future
> I1026 05:37:43.171516 1947 slave.cpp:3297] Current usage 90.00%. Max
> allowed age: 0ns
> I1026 05:37:43.171716 1943 hierarchical_allocator_process.hpp:734]
> Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on
> slave 20141026-053740-3142697795-48044-1919-S0 to framework
> 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.171942 1943 hierarchical_allocator_process.hpp:659]
> Performed allocation for 1 slaves in 414547ns
> I1026 05:37:43.172202 1943 master.cpp:3795] Sending 1 offers to framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:43.172498 1947 sched.cpp:544] Scheduler::resourceOffers took
> 21756ns
> ../../src/tests/gc_tests.cpp:675: Failure
> Value of: os::exists(executorDir)
> Actual: true
> Expected: false
> I1026 05:37:43.191849 1944 master.cpp:768] Framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> disconnected
> I1026 05:37:43.191870 1944 master.cpp:1731] Disconnecting framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:43.191889 1944 master.cpp:1747] Deactivating framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:43.191998 1946 hierarchical_allocator_process.hpp:405]
> Deactivated framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.192348 1944 master.cpp:790] Giving framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 0ns to
> failover
> I1026 05:37:43.192411 1943 hierarchical_allocator_process.hpp:563]
> Recovered disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024
> (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2;
> mem(*):1024) on slave 20141026-053740-3142697795-48044-1919-S0 from
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.192466 1944 master.cpp:3665] Framework failover timeout,
> removing framework 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:43.192481 1944 master.cpp:4201] Removing framework
> 20141026-053740-3142697795-48044-1919-0000 (default) at
> scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
> I1026 05:37:43.192610 1945 slave.cpp:1522] Asked to shut down framework
> 20141026-053740-3142697795-48044-1919-0000 by master@67.195.81.187:48044
> W1026 05:37:43.192631 1945 slave.cpp:1537] Cannot shut down unknown
> framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.192734 1944 hierarchical_allocator_process.hpp:360]
> Removed framework 20141026-053740-3142697795-48044-1919-0000
> I1026 05:37:43.193313 1945 master.cpp:677] Master terminating
> I1026 05:37:43.193547 1945 slave.cpp:2607] master@67.195.81.187:48044
> exited
> W1026 05:37:43.193560 1945 slave.cpp:2610] Master disconnected! Waiting
> for a new master to be elected
> *** Aborted at 1414301863 (unix time) try "date -d @1414301863" if you are
> using GNU date ***
> PC: @ 0x211 (unknown)
> make[3]: *** [check-local] Segmentation fault
> make[3]: Leaving directory `<
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src
> '>
> make[2]: *** [check-am] Error 2
> make[2]: Leaving directory `<
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src
> '>
> make[1]: *** [check] Error 2
> make[1]: Leaving directory `<
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src
> '>
> make: *** [check-recursive] Error 1
> Test finished
> Exit status: 2
> Build step 'Execute shell' marked build as failure
> Recording test results
>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#2488
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2488/changes>
Changes:
[yujie.jay] PortMappingIsolator: Swap TX/RX in statistic collection in usage
------------------------------------------
[...truncated 21400 lines...]
I1026 05:37:40.692209 1944 master.cpp:1242] The newly elected leader is master@67.195.81.187:48044 with id 20141026-053740-3142697795-48044-1919
I1026 05:37:40.692231 1944 master.cpp:1255] Elected as the leading master!
I1026 05:37:40.692258 1944 master.cpp:1073] Recovering from registrar
I1026 05:37:40.692318 1938 recover.cpp:188] Received a recover response from a replica in STARTING status
I1026 05:37:40.692419 1947 registrar.cpp:313] Recovering registrar
I1026 05:37:40.692847 1936 recover.cpp:554] Updating replica status to VOTING
I1026 05:37:40.717001 1939 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 763823ns
I1026 05:37:40.717027 1939 replica.cpp:320] Persisted replica status to VOTING
I1026 05:37:40.717118 1948 recover.cpp:568] Successfully joined the Paxos group
I1026 05:37:40.717316 1948 recover.cpp:452] Recover process terminated
I1026 05:37:40.717768 1934 log.cpp:656] Attempting to start the writer
I1026 05:37:40.719187 1935 replica.cpp:474] Replica received implicit promise request with proposal 1
I1026 05:37:40.719761 1935 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 553560ns
I1026 05:37:40.719779 1935 replica.cpp:342] Persisted promised to 1
I1026 05:37:40.720424 1945 coordinator.cpp:230] Coordinator attemping to fill missing position
I1026 05:37:40.721951 1933 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I1026 05:37:40.722365 1933 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 385625ns
I1026 05:37:40.722385 1933 replica.cpp:676] Persisted action at 0
I1026 05:37:40.723311 1939 replica.cpp:508] Replica received write request for position 0
I1026 05:37:40.723372 1939 leveldb.cpp:438] Reading position from leveldb took 35296ns
I1026 05:37:40.723793 1939 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 387051ns
I1026 05:37:40.723814 1939 replica.cpp:676] Persisted action at 0
I1026 05:37:40.724311 1942 replica.cpp:655] Replica received learned notice for position 0
I1026 05:37:40.724762 1942 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 424112ns
I1026 05:37:40.724787 1942 replica.cpp:676] Persisted action at 0
I1026 05:37:40.724809 1942 replica.cpp:661] Replica learned NOP action at position 0
I1026 05:37:40.725275 1936 log.cpp:672] Writer started with ending position 0
I1026 05:37:40.726511 1945 leveldb.cpp:438] Reading position from leveldb took 33201ns
I1026 05:37:40.729558 1947 registrar.cpp:346] Successfully fetched the registry (0B) in 37.101056ms
I1026 05:37:40.729665 1947 registrar.cpp:445] Applied 1 operations in 19075ns; attempting to update the 'registry'
I1026 05:37:40.732529 1937 log.cpp:680] Attempting to append 138 bytes to the log
I1026 05:37:40.732743 1943 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I1026 05:37:40.733597 1940 replica.cpp:508] Replica received write request for position 1
I1026 05:37:40.734328 1940 leveldb.cpp:343] Persisting action (157 bytes) to leveldb took 702340ns
I1026 05:37:40.734354 1940 replica.cpp:676] Persisted action at 1
I1026 05:37:40.734871 1939 replica.cpp:655] Replica received learned notice for position 1
I1026 05:37:40.735640 1939 leveldb.cpp:343] Persisting action (159 bytes) to leveldb took 743999ns
I1026 05:37:40.735673 1939 replica.cpp:676] Persisted action at 1
I1026 05:37:40.735704 1939 replica.cpp:661] Replica learned APPEND action at position 1
I1026 05:37:40.736652 1940 registrar.cpp:490] Successfully updated the 'registry' in 6.947072ms
I1026 05:37:40.736780 1940 registrar.cpp:376] Successfully recovered registrar
I1026 05:37:40.737062 1936 master.cpp:1100] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register
I1026 05:37:40.737082 1940 log.cpp:699] Attempting to truncate the log to 1
I1026 05:37:40.737210 1934 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2
I1026 05:37:40.737901 1941 replica.cpp:508] Replica received write request for position 2
I1026 05:37:40.738412 1941 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 484781ns
I1026 05:37:40.738432 1941 replica.cpp:676] Persisted action at 2
I1026 05:37:40.738870 1941 replica.cpp:655] Replica received learned notice for position 2
I1026 05:37:40.739341 1941 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 451900ns
I1026 05:37:40.739384 1941 leveldb.cpp:401] Deleting ~1 keys from leveldb took 28203ns
I1026 05:37:40.739397 1941 replica.cpp:676] Persisted action at 2
I1026 05:37:40.739411 1941 replica.cpp:661] Replica learned TRUNCATE action at position 2
I1026 05:37:40.751147 1935 slave.cpp:169] Slave started on 71)@67.195.81.187:48044
I1026 05:37:40.751179 1935 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/credential'
I1026 05:37:40.751363 1935 slave.cpp:276] Slave using credential for: test-principal
I1026 05:37:40.751664 1935 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1026 05:37:40.751751 1935 slave.cpp:318] Slave hostname: pomona.apache.org
I1026 05:37:40.751765 1935 slave.cpp:319] Slave checkpoint: false
W1026 05:37:40.751775 1935 slave.cpp:321] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag
I1026 05:37:40.752568 1945 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/meta'
I1026 05:37:40.752869 1945 status_update_manager.cpp:197] Recovering status update manager
I1026 05:37:40.753314 1940 slave.cpp:3442] Finished recovery
I1026 05:37:40.753865 1935 slave.cpp:602] New master detected at master@67.195.81.187:48044
I1026 05:37:40.753885 1935 slave.cpp:665] Authenticating with master master@67.195.81.187:48044
I1026 05:37:40.753962 1935 slave.cpp:638] Detecting new master
I1026 05:37:40.753981 1943 status_update_manager.cpp:171] Pausing sending status updates
I1026 05:37:40.754030 1939 authenticatee.hpp:133] Creating new client SASL connection
I1026 05:37:40.754212 1944 master.cpp:3853] Authenticating slave(71)@67.195.81.187:48044
I1026 05:37:40.754413 1937 authenticator.hpp:161] Creating new server SASL connection
I1026 05:37:40.754632 1948 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5
I1026 05:37:40.754653 1948 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5'
I1026 05:37:40.754703 1948 authenticator.hpp:267] Received SASL authentication start
I1026 05:37:40.754739 1948 authenticator.hpp:389] Authentication requires more steps
I1026 05:37:40.754792 1948 authenticatee.hpp:270] Received SASL authentication step
I1026 05:37:40.754848 1948 authenticator.hpp:295] Received SASL authentication step
I1026 05:37:40.754866 1948 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1026 05:37:40.754874 1948 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I1026 05:37:40.754927 1948 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1026 05:37:40.754945 1948 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1026 05:37:40.754951 1948 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1026 05:37:40.754956 1948 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1026 05:37:40.754966 1948 authenticator.hpp:381] Authentication success
I1026 05:37:40.755040 1948 authenticatee.hpp:310] Authentication success
I1026 05:37:40.755082 1936 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(71)@67.195.81.187:48044
I1026 05:37:40.755187 1937 slave.cpp:722] Successfully authenticated with master master@67.195.81.187:48044
I1026 05:37:40.755321 1937 slave.cpp:1050] Will retry registration in 8.911789ms if necessary
I1026 05:37:40.755475 1945 master.cpp:3032] Registering slave at slave(71)@67.195.81.187:48044 (pomona.apache.org) with id 20141026-053740-3142697795-48044-1919-S0
I1026 05:37:40.755848 1945 registrar.cpp:445] Applied 1 operations in 47887ns; attempting to update the 'registry'
I1026 05:37:40.758046 1937 log.cpp:680] Attempting to append 314 bytes to the log
I1026 05:37:40.764782 1933 slave.cpp:1050] Will retry registration in 17.226691ms if necessary
I1026 05:37:40.764904 1944 master.cpp:3020] Ignoring register slave message from slave(71)@67.195.81.187:48044 (pomona.apache.org) as admission is already in progress
I1026 05:37:40.820008 1938 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3
I1026 05:37:40.820843 1941 replica.cpp:508] Replica received write request for position 3
I1026 05:37:40.821717 1941 leveldb.cpp:343] Persisting action (333 bytes) to leveldb took 846259ns
I1026 05:37:40.821735 1941 replica.cpp:676] Persisted action at 3
I1026 05:37:40.822347 1934 replica.cpp:655] Replica received learned notice for position 3
I1026 05:37:40.823001 1934 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 628489ns
I1026 05:37:40.823026 1934 replica.cpp:676] Persisted action at 3
I1026 05:37:40.823048 1934 replica.cpp:661] Replica learned APPEND action at position 3
I1026 05:37:40.824146 1935 registrar.cpp:490] Successfully updated the 'registry' in 68.205056ms
I1026 05:37:40.824504 1936 log.cpp:699] Attempting to truncate the log to 3
I1026 05:37:40.824625 1935 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4
I1026 05:37:40.824702 1936 slave.cpp:2522] Received ping from slave-observer(56)@67.195.81.187:48044
I1026 05:37:40.824784 1942 master.cpp:3086] Registered slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1026 05:37:40.824972 1936 slave.cpp:756] Registered with master master@67.195.81.187:48044; given slave ID 20141026-053740-3142697795-48044-1919-S0
I1026 05:37:40.825006 1948 hierarchical_allocator_process.hpp:442] Added slave 20141026-053740-3142697795-48044-1919-S0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I1026 05:37:40.825063 1941 status_update_manager.cpp:178] Resuming sending status updates
I1026 05:37:40.825163 1948 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141026-053740-3142697795-48044-1919-S0 in 57286ns
I1026 05:37:40.825516 1943 replica.cpp:508] Replica received write request for position 4
I1026 05:37:40.826031 1943 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 487705ns
I1026 05:37:40.826053 1943 replica.cpp:676] Persisted action at 4
I1026 05:37:40.826514 1941 replica.cpp:655] Replica received learned notice for position 4
I1026 05:37:40.826992 1941 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 457616ns
I1026 05:37:40.827054 1941 leveldb.cpp:401] Deleting ~2 keys from leveldb took 41154ns
I1026 05:37:40.827070 1941 replica.cpp:676] Persisted action at 4
I1026 05:37:40.827090 1941 replica.cpp:661] Replica learned TRUNCATE action at position 4
I1026 05:37:40.829262 1919 sched.cpp:137] Version: 0.21.0
I1026 05:37:40.829761 1939 sched.cpp:233] New master detected at master@67.195.81.187:48044
I1026 05:37:40.829787 1939 sched.cpp:283] Authenticating with master master@67.195.81.187:48044
I1026 05:37:40.829941 1936 authenticatee.hpp:133] Creating new client SASL connection
I1026 05:37:40.830123 1943 master.cpp:3853] Authenticating scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:40.830319 1938 authenticator.hpp:161] Creating new server SASL connection
I1026 05:37:40.830438 1942 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5
I1026 05:37:40.830461 1942 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5'
I1026 05:37:40.830538 1946 authenticator.hpp:267] Received SASL authentication start
I1026 05:37:40.830588 1946 authenticator.hpp:389] Authentication requires more steps
I1026 05:37:40.830651 1946 authenticatee.hpp:270] Received SASL authentication step
I1026 05:37:40.830736 1936 authenticator.hpp:295] Received SASL authentication step
I1026 05:37:40.830762 1936 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1026 05:37:40.830773 1936 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I1026 05:37:40.830808 1936 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1026 05:37:40.830829 1936 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1026 05:37:40.830838 1936 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1026 05:37:40.830844 1936 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1026 05:37:40.830858 1936 authenticator.hpp:381] Authentication success
I1026 05:37:40.830924 1942 authenticatee.hpp:310] Authentication success
I1026 05:37:40.830951 1934 master.cpp:3893] Successfully authenticated principal 'test-principal' at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:40.831087 1948 sched.cpp:357] Successfully authenticated with master master@67.195.81.187:48044
I1026 05:37:40.831109 1948 sched.cpp:476] Sending registration request to master@67.195.81.187:48044
I1026 05:37:40.831220 1946 master.cpp:1362] Received registration request for framework 'default' at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:40.831306 1946 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1026 05:37:40.831666 1946 master.cpp:1426] Registering framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:40.831886 1948 hierarchical_allocator_process.hpp:329] Added framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.831992 1943 sched.cpp:407] Framework registered with 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.832056 1943 sched.cpp:421] Scheduler::registered took 39567ns
I1026 05:37:40.832061 1948 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141026-053740-3142697795-48044-1919-S0 to framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.832465 1948 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 557029ns
I1026 05:37:40.832742 1947 master.cpp:3795] Sending 1 offers to framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:40.833416 1938 sched.cpp:544] Scheduler::resourceOffers took 413267ns
I1026 05:37:40.834154 1944 master.cpp:2321] Processing reply for offers: [ 20141026-053740-3142697795-48044-1919-O0 ] on slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org) for framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
W1026 05:37:40.834249 1944 master.cpp:1969] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1026 05:37:40.834276 1944 master.cpp:1980] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1026 05:37:40.834388 1944 master.cpp:2417] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins'
I1026 05:37:40.835225 1935 master.hpp:877] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141026-053740-3142697795-48044-1919-S0 (pomona.apache.org)
I1026 05:37:40.835311 1935 master.cpp:2480] Launching task 0 of framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 with resources cpus(*):2; mem(*):1024 on slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:40.985780 1940 slave.cpp:1081] Got assigned task 0 for framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.986291 1941 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141026-053740-3142697795-48044-1919-S0 from framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.986400 1941 hierarchical_allocator_process.hpp:599] Framework 20141026-053740-3142697795-48044-1919-0000 filtered slave 20141026-053740-3142697795-48044-1919-S0 for 5secs
I1026 05:37:40.986466 1940 slave.cpp:1191] Launching task 0 for framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.989169 1940 slave.cpp:3857] Launching executor default of framework 20141026-053740-3142697795-48044-1919-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91'
I1026 05:37:40.991880 1940 exec.cpp:132] Version: 0.21.0
I1026 05:37:40.992161 1939 exec.cpp:182] Executor started at: executor(12)@67.195.81.187:48044 with pid 1919
I1026 05:37:40.992316 1940 slave.cpp:1317] Queuing task '0' for executor default of framework '20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.992468 1940 slave.cpp:555] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91'
I1026 05:37:40.992540 1940 slave.cpp:1849] Got registration for executor 'default' of framework 20141026-053740-3142697795-48044-1919-0000 from executor(12)@67.195.81.187:48044
I1026 05:37:40.992796 1940 slave.cpp:1968] Flushing queued task 0 for executor 'default' of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.992856 1933 exec.cpp:206] Executor registered on slave 20141026-053740-3142697795-48044-1919-S0
I1026 05:37:40.992960 1940 slave.cpp:2788] Monitoring executor 'default' of framework '20141026-053740-3142697795-48044-1919-0000' in container '51de1be1-00e4-45be-ba30-b017c1067a91'
I1026 05:37:40.994954 1933 exec.cpp:218] Executor::registered took 26527ns
I1026 05:37:40.995110 1933 exec.cpp:293] Executor asked to run task '0'
I1026 05:37:40.995173 1933 exec.cpp:302] Executor::launchTask took 47056ns
I1026 05:37:40.997432 1933 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.997653 1933 slave.cpp:2202] Handling status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 from executor(12)@67.195.81.187:48044
I1026 05:37:40.997896 1947 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.997936 1947 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.998147 1947 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to the slave
I1026 05:37:40.998363 1934 slave.cpp:2442] Forwarding the update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to master@67.195.81.187:48044
I1026 05:37:40.998481 1934 slave.cpp:2369] Status update manager successfully handled status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.998504 1934 slave.cpp:2375] Sending acknowledgement for status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to executor(12)@67.195.81.187:48044
I1026 05:37:40.998644 1940 exec.cpp:339] Executor received status update acknowledgement 26f54ad4-b9bc-4d89-894e-f29a708f5a4d for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.998677 1944 master.cpp:3410] Forwarding status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.998766 1944 master.cpp:3382] Status update TASK_RUNNING (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 from slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:40.998797 1944 master.cpp:4617] Updating the latest state of task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to TASK_RUNNING
I1026 05:37:40.998863 1948 sched.cpp:635] Scheduler::statusUpdate took 41522ns
I1026 05:37:40.999078 1938 master.cpp:2882] Forwarding status update acknowledgement 26f54ad4-b9bc-4d89-894e-f29a708f5a4d for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 to slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:40.999328 1938 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:40.999362 1941 slave.cpp:2846] Executor 'default' of framework 20141026-053740-3142697795-48044-1919-0000 exited with status 0
I1026 05:37:41.001579 1941 slave.cpp:2202] Handling status update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 from @0.0.0.0:0
I1026 05:37:41.001646 1941 slave.cpp:4165] Terminating task 0
I1026 05:37:41.001994 1941 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: 26f54ad4-b9bc-4d89-894e-f29a708f5a4d) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:41.002087 1944 master.cpp:3462] Executor default of framework 20141026-053740-3142697795-48044-1919-0000 on slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org) exited with status 0
I1026 05:37:41.002166 1944 master.cpp:4705] Removing executor 'default' with resources of framework 20141026-053740-3142697795-48044-1919-0000 on slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:41.002255 1933 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:41.002393 1933 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to the slave
I1026 05:37:41.002594 1935 slave.cpp:2442] Forwarding the update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to master@67.195.81.187:48044
I1026 05:37:41.002760 1935 slave.cpp:2369] Status update manager successfully handled status update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:41.002810 1934 master.cpp:3410] Forwarding status update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.157433 1934 master.cpp:3382] Status update TASK_LOST (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 from slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:43.157491 1934 master.cpp:4617] Updating the latest state of task 0 of framework 20141026-053740-3142697795-48044-1919-0000 to TASK_LOST
I1026 05:37:43.157644 1940 sched.cpp:635] Scheduler::statusUpdate took 20710ns
I1026 05:37:43.157738 1946 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141026-053740-3142697795-48044-1919-S0 from framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.157824 1940 master.cpp:4676] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141026-053740-3142697795-48044-1919-0000 on slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:43.157938 1940 master.cpp:2882] Forwarding status update acknowledgement d749f825-9535-42e9-aac0-a5384af9269f for task 0 of framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 to slave 20141026-053740-3142697795-48044-1919-S0 at slave(71)@67.195.81.187:48044 (pomona.apache.org)
I1026 05:37:43.158191 1948 status_update_manager.cpp:389] Received status update acknowledgement (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.158341 1948 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.158565 1941 slave.cpp:1789] Status update manager successfully handled status update acknowledgement (UUID: d749f825-9535-42e9-aac0-a5384af9269f) for task 0 of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.158627 1941 slave.cpp:4204] Completing task 0
I1026 05:37:43.158670 1941 slave.cpp:2983] Cleaning up executor 'default' of framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.158879 1947 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default/runs/51de1be1-00e4-45be-ba30-b017c1067a91' for gc 1.00000330839619weeks in the future
I1026 05:37:43.159008 1941 slave.cpp:3060] Cleaning up framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.159097 1947 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000/executors/default' for gc 1.00000330839619weeks in the future
I1026 05:37:43.159159 1941 status_update_manager.cpp:279] Closing status update streams for framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.159184 1947 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xuy4QG/slaves/20141026-053740-3142697795-48044-1919-S0/frameworks/20141026-053740-3142697795-48044-1919-0000' for gc 1.00000330839619weeks in the future
I1026 05:37:43.171516 1947 slave.cpp:3297] Current usage 90.00%. Max allowed age: 0ns
I1026 05:37:43.171716 1943 hierarchical_allocator_process.hpp:734] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20141026-053740-3142697795-48044-1919-S0 to framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.171942 1943 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 414547ns
I1026 05:37:43.172202 1943 master.cpp:3795] Sending 1 offers to framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:43.172498 1947 sched.cpp:544] Scheduler::resourceOffers took 21756ns
../../src/tests/gc_tests.cpp:675: Failure
Value of: os::exists(executorDir)
Actual: true
Expected: false
I1026 05:37:43.191849 1944 master.cpp:768] Framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 disconnected
I1026 05:37:43.191870 1944 master.cpp:1731] Disconnecting framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:43.191889 1944 master.cpp:1747] Deactivating framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:43.191998 1946 hierarchical_allocator_process.hpp:405] Deactivated framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.192348 1944 master.cpp:790] Giving framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044 0ns to failover
I1026 05:37:43.192411 1943 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141026-053740-3142697795-48044-1919-S0 from framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.192466 1944 master.cpp:3665] Framework failover timeout, removing framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:43.192481 1944 master.cpp:4201] Removing framework 20141026-053740-3142697795-48044-1919-0000 (default) at scheduler-cb1df4cd-8f22-46e3-8dc6-3aefa158d12a@67.195.81.187:48044
I1026 05:37:43.192610 1945 slave.cpp:1522] Asked to shut down framework 20141026-053740-3142697795-48044-1919-0000 by master@67.195.81.187:48044
W1026 05:37:43.192631 1945 slave.cpp:1537] Cannot shut down unknown framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.192734 1944 hierarchical_allocator_process.hpp:360] Removed framework 20141026-053740-3142697795-48044-1919-0000
I1026 05:37:43.193313 1945 master.cpp:677] Master terminating
I1026 05:37:43.193547 1945 slave.cpp:2607] master@67.195.81.187:48044 exited
W1026 05:37:43.193560 1945 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected
*** Aborted at 1414301863 (unix time) try "date -d @1414301863" if you are using GNU date ***
PC: @ 0x211 (unknown)
make[3]: *** [check-local] Segmentation fault
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results