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/07/23 22:05:07 UTC
Build failed in Jenkins: Mesos-Ubuntu-distcheck #234
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/234/changes>
Changes:
[dhamon] Introduce script for generating test coverage report.
------------------------------------------
[...truncated 4436 lines...]
make[4]: Nothing to be done for `examples/python/test-containerizer'.
make[4]: Nothing to be done for `../../src/examples/python/test_framework.py'.
make[4]: Nothing to be done for `examples/python/test-framework'.
make[4]: Nothing to be done for `../../src/examples/python/test_executor.py'.
make[4]: Nothing to be done for `examples/python/test-executor'.
make[4]: Nothing to be done for `../../src/tests/balloon_framework_test.sh'.
make[4]: Nothing to be done for `../../src/tests/low_level_scheduler_libprocess_test.sh'.
make[4]: Nothing to be done for `../../src/tests/low_level_scheduler_pthread_test.sh'.
make[4]: Nothing to be done for `../../src/tests/java_exception_test.sh'.
make[4]: Nothing to be done for `../../src/tests/java_framework_test.sh'.
make[4]: Nothing to be done for `../../src/tests/java_log_test.sh'.
make[4]: Nothing to be done for `../../src/tests/no_executor_framework_test.sh'.
make[4]: Nothing to be done for `../../src/tests/python_framework_test.sh'.
make[4]: Nothing to be done for `../../src/tests/test_framework_test.sh'.
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build/src'>
make check-local
make[4]: Entering directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build/src'>
./mesos-tests
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0723 20:05:02.677297 9531 process.cpp:1671] libprocess is initialized on 67.195.138.60:54168 for 8 cpus
I0723 20:05:02.677456 9531 logging.cpp:172] Logging to STDERR
Source directory: <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0>
Build directory: <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build>
Note: Google Test filter = *-CpuIsolatorTest/1.UserCpuUsage:CpuIsolatorTest/1.SystemCpuUsage:LimitedCpuIsolatorTest.ROOT_CGROUPS_Cfs:LimitedCpuIsolatorTest.ROOT_CGROUPS_Cfs_Big_Quota:MemIsolatorTest/0.MemUsage:MemIsolatorTest/1.MemUsage:PerfEventIsolatorTest.ROOT_CGROUPS_Sample:MesosContainerizerSlaveRecoveryTest.CGROUPS_ROOT_PerfRollForward:SlaveTest.ROOT_RunTaskWithCommandInfoWithoutUser:SlaveTest.DISABLED_ROOT_RunTaskWithCommandInfoWithUser:ContainerizerTest.ROOT_CGROUPS_BalloonFramework:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Enabled:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Subsystems:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Mounted:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Get:CgroupsAnyHierarchyTest.ROOT_CGROUPS_NestedCgroups:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Tasks:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Read:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Write:CgroupsAnyHierarchyTest.ROOT_CGROUPS_Cfs_Big_Quota:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_Busy:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_SubsystemsHierarchy:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_MountedSubsystems:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_CreateRemove:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_Listen:CgroupsAnyHierarchyWithCpuMemoryTest.ROOT_CGROUPS_FreezeNonFreezer:CgroupsNoHierarchyTest.ROOT_CGROUPS_NOHIERARCHY_MountUnmountHierarchy:CgroupsAnyHierarchyWithCpuAcctMemoryTest.ROOT_CGROUPS_Stat:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_Freeze:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_Kill:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_Destroy:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_AssignThreads:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_DestroyStoppedProcess:CgroupsAnyHierarchyWithFreezerTest.ROOT_CGROUPS_DestroyTracedProcess:CgroupsAnyHierarchyWithPerfEventTest.ROOT_CGROUPS_Perf:PerfTest.ROOT_Events:PerfTest.ROOT_SampleInit:SlaveCount/Registrar_BENCHMARK_Test.performance/0:SlaveCount/Registrar_BENCHMARK_Test.performance/1:SlaveCount/Registrar_BENCHMARK_Test.performance/2:SlaveCount/Registrar_BENCHMARK_Test.performance/3:
Note: Randomizing tests' orders with a seed of 13813 .
[==========] Running 390 tests from 64 test cases.
[----------] Global test environment set-up.
[----------] 4 tests from ReplicaTest
[ RUN ] ReplicaTest.Restore
Using temporary directory '/tmp/ReplicaTest_Restore_azbVEB'
I0723 20:05:02.820477 9531 leveldb.cpp:176] Opened db in 130.560191ms
I0723 20:05:02.833539 9531 leveldb.cpp:183] Compacted db in 13.015365ms
I0723 20:05:02.833571 9531 leveldb.cpp:198] Created db iterator in 12587ns
I0723 20:05:02.833582 9531 leveldb.cpp:204] Seeked to beginning of db in 786ns
I0723 20:05:02.833591 9531 leveldb.cpp:273] Iterated through 0 keys in the db in 2148ns
I0723 20:05:02.833626 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:02.870609 9562 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 36.259854ms
I0723 20:05:02.870638 9562 replica.cpp:320] Persisted replica status to VOTING
I0723 20:05:02.962617 9531 leveldb.cpp:176] Opened db in 91.648844ms
I0723 20:05:03.057539 9531 leveldb.cpp:183] Compacted db in 94.90146ms
I0723 20:05:03.057561 9531 leveldb.cpp:198] Created db iterator in 4944ns
I0723 20:05:03.057576 9531 leveldb.cpp:204] Seeked to beginning of db in 7131ns
I0723 20:05:03.057605 9531 leveldb.cpp:273] Iterated through 1 keys in the db in 20897ns
I0723 20:05:03.057622 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.057873 9565 replica.cpp:474] Replica received implicit promise request with proposal 1
I0723 20:05:03.079975 9565 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 22.087375ms
I0723 20:05:03.079996 9565 replica.cpp:342] Persisted promised to 1
I0723 20:05:03.080302 9563 replica.cpp:508] Replica received write request for position 1
I0723 20:05:03.089287 9563 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 8.937714ms
I0723 20:05:03.089314 9563 replica.cpp:676] Persisted action at 1
I0723 20:05:03.089478 9562 leveldb.cpp:438] Reading position from leveldb took 16967ns
I0723 20:05:03.169229 9531 leveldb.cpp:176] Opened db in 79.638397ms
I0723 20:05:03.223675 9531 leveldb.cpp:183] Compacted db in 54.426124ms
I0723 20:05:03.223697 9531 leveldb.cpp:198] Created db iterator in 3369ns
I0723 20:05:03.223711 9531 leveldb.cpp:204] Seeked to beginning of db in 5256ns
I0723 20:05:03.223732 9531 leveldb.cpp:273] Iterated through 2 keys in the db in 11810ns
I0723 20:05:03.223757 9531 replica.cpp:741] Replica recovered with log positions 0 -> 1 with 1 holes and 1 unlearned
I0723 20:05:03.223883 9567 leveldb.cpp:438] Reading position from leveldb took 32404ns
[ OK ] ReplicaTest.Restore (535 ms)
[ RUN ] ReplicaTest.Append
Using temporary directory '/tmp/ReplicaTest_Append_9EWROf'
I0723 20:05:03.303537 9531 leveldb.cpp:176] Opened db in 78.776485ms
I0723 20:05:03.307235 9531 leveldb.cpp:183] Compacted db in 3.675494ms
I0723 20:05:03.307251 9531 leveldb.cpp:198] Created db iterator in 1638ns
I0723 20:05:03.307260 9531 leveldb.cpp:204] Seeked to beginning of db in 244ns
I0723 20:05:03.307265 9531 leveldb.cpp:273] Iterated through 0 keys in the db in 75ns
I0723 20:05:03.307272 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.331554 9568 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 24.052034ms
I0723 20:05:03.331576 9568 replica.cpp:320] Persisted replica status to VOTING
I0723 20:05:03.415268 9531 leveldb.cpp:176] Opened db in 81.20263ms
I0723 20:05:03.465780 9531 leveldb.cpp:183] Compacted db in 50.489596ms
I0723 20:05:03.465801 9531 leveldb.cpp:198] Created db iterator in 3348ns
I0723 20:05:03.465812 9531 leveldb.cpp:204] Seeked to beginning of db in 4563ns
I0723 20:05:03.465827 9531 leveldb.cpp:273] Iterated through 1 keys in the db in 8127ns
I0723 20:05:03.465839 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.466018 9562 replica.cpp:474] Replica received implicit promise request with proposal 1
I0723 20:05:03.499269 9562 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 33.233231ms
I0723 20:05:03.499289 9562 replica.cpp:342] Persisted promised to 1
I0723 20:05:03.499519 9563 replica.cpp:508] Replica received write request for position 1
I0723 20:05:03.509457 9563 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 9.920712ms
I0723 20:05:03.509477 9563 replica.cpp:676] Persisted action at 1
I0723 20:05:03.509661 9562 leveldb.cpp:438] Reading position from leveldb took 35038ns
[ OK ] ReplicaTest.Append (286 ms)
[ RUN ] ReplicaTest.Promise
Using temporary directory '/tmp/ReplicaTest_Promise_7Tb8KU'
I0723 20:05:03.591603 9531 leveldb.cpp:176] Opened db in 81.298388ms
I0723 20:05:03.601577 9531 leveldb.cpp:183] Compacted db in 9.955467ms
I0723 20:05:03.601596 9531 leveldb.cpp:198] Created db iterator in 2266ns
I0723 20:05:03.601605 9531 leveldb.cpp:204] Seeked to beginning of db in 378ns
I0723 20:05:03.601611 9531 leveldb.cpp:273] Iterated through 0 keys in the db in 96ns
I0723 20:05:03.601621 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.627142 9565 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 25.311039ms
I0723 20:05:03.627164 9565 replica.cpp:320] Persisted replica status to VOTING
I0723 20:05:03.708798 9531 leveldb.cpp:176] Opened db in 81.396594ms
I0723 20:05:03.760118 9531 leveldb.cpp:183] Compacted db in 51.299023ms
I0723 20:05:03.760141 9531 leveldb.cpp:198] Created db iterator in 3414ns
I0723 20:05:03.760155 9531 leveldb.cpp:204] Seeked to beginning of db in 5151ns
I0723 20:05:03.760170 9531 leveldb.cpp:273] Iterated through 1 keys in the db in 6981ns
I0723 20:05:03.760184 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.760293 9569 replica.cpp:474] Replica received implicit promise request with proposal 2
I0723 20:05:03.794700 9569 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 34.385816ms
I0723 20:05:03.794719 9569 replica.cpp:342] Persisted promised to 2
I0723 20:05:03.794929 9567 replica.cpp:474] Replica received implicit promise request with proposal 1
I0723 20:05:03.794955 9567 replica.cpp:479] Replica denying promise request with proposal 1
I0723 20:05:03.795132 9563 replica.cpp:474] Replica received implicit promise request with proposal 3
I0723 20:05:03.805011 9563 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 9.865914ms
I0723 20:05:03.805029 9563 replica.cpp:342] Persisted promised to 3
[ OK ] ReplicaTest.Promise (295 ms)
[ RUN ] ReplicaTest.NonVoting
Using temporary directory '/tmp/ReplicaTest_NonVoting_JUclvA'
I0723 20:05:03.884166 9531 leveldb.cpp:176] Opened db in 78.671821ms
I0723 20:05:03.893031 9531 leveldb.cpp:183] Compacted db in 8.845201ms
I0723 20:05:03.893049 9531 leveldb.cpp:198] Created db iterator in 1848ns
I0723 20:05:03.893057 9531 leveldb.cpp:204] Seeked to beginning of db in 288ns
I0723 20:05:03.893064 9531 leveldb.cpp:273] Iterated through 0 keys in the db in 96ns
I0723 20:05:03.893074 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:03.893236 9567 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0723 20:05:03.903360 9569 replica.cpp:503] Replica ignoring write request as it is in EMPTY status
[ OK ] ReplicaTest.NonVoting (108 ms)
[----------] 4 tests from ReplicaTest (1224 ms total)
[----------] 20 tests from MasterTest
[ RUN ] MasterTest.TaskRunning
Using temporary directory '/tmp/MasterTest_TaskRunning_oeVRxg'
I0723 20:05:04.007910 9531 leveldb.cpp:176] Opened db in 92.149125ms
I0723 20:05:04.034940 9531 leveldb.cpp:183] Compacted db in 27.008077ms
I0723 20:05:04.034956 9531 leveldb.cpp:198] Created db iterator in 2250ns
I0723 20:05:04.034963 9531 leveldb.cpp:204] Seeked to beginning of db in 273ns
I0723 20:05:04.034968 9531 leveldb.cpp:273] Iterated through 0 keys in the db in 90ns
I0723 20:05:04.034977 9531 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0723 20:05:04.035289 9565 recover.cpp:425] Starting replica recovery
I0723 20:05:04.035431 9565 recover.cpp:451] Replica is in EMPTY status
I0723 20:05:04.035948 9565 replica.cpp:638] Replica in EMPTY status received a broadcasted recover request
I0723 20:05:04.036198 9563 recover.cpp:188] Received a recover response from a replica in EMPTY status
I0723 20:05:04.036331 9562 recover.cpp:542] Updating replica status to STARTING
I0723 20:05:04.036669 9565 master.cpp:289] Master 20140723-200504-1015726915-54168-9531 (janus.apache.org) started on 67.195.138.60:54168
I0723 20:05:04.036720 9565 master.cpp:326] Master only allowing authenticated frameworks to register
I0723 20:05:04.036731 9565 master.cpp:331] Master only allowing authenticated slaves to register
I0723 20:05:04.036746 9565 credentials.hpp:36] Loading credentials for authentication from '/tmp/MasterTest_TaskRunning_oeVRxg/credentials'
I0723 20:05:04.037014 9565 master.cpp:360] Authorization enabled
I0723 20:05:04.037386 9564 master.cpp:123] No whitelist given. Advertising offers for all slaves
I0723 20:05:04.037472 9569 hierarchical_allocator_process.hpp:301] Initializing hierarchical allocator process with master : master@67.195.138.60:54168
I0723 20:05:04.038990 9563 master.cpp:1129] The newly elected leader is master@67.195.138.60:54168 with id 20140723-200504-1015726915-54168-9531
I0723 20:05:04.039012 9563 master.cpp:1142] Elected as the leading master!
I0723 20:05:04.039021 9563 master.cpp:960] Recovering from registrar
I0723 20:05:04.039094 9564 registrar.cpp:313] Recovering registrar
I0723 20:05:04.060128 9562 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 23.69529ms
I0723 20:05:04.060153 9562 replica.cpp:320] Persisted replica status to STARTING
I0723 20:05:04.060281 9564 recover.cpp:451] Replica is in STARTING status
I0723 20:05:04.060616 9563 replica.cpp:638] Replica in STARTING status received a broadcasted recover request
I0723 20:05:04.060734 9569 recover.cpp:188] Received a recover response from a replica in STARTING status
I0723 20:05:04.060883 9566 recover.cpp:542] Updating replica status to VOTING
I0723 20:05:04.070415 9562 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 9.447275ms
I0723 20:05:04.070435 9562 replica.cpp:320] Persisted replica status to VOTING
I0723 20:05:04.070488 9566 recover.cpp:556] Successfully joined the Paxos group
I0723 20:05:04.070591 9566 recover.cpp:440] Recover process terminated
I0723 20:05:04.070788 9562 log.cpp:656] Attempting to start the writer
I0723 20:05:04.071338 9564 replica.cpp:474] Replica received implicit promise request with proposal 1
I0723 20:05:04.078738 9564 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 7.385605ms
I0723 20:05:04.078757 9564 replica.cpp:342] Persisted promised to 1
I0723 20:05:04.079032 9563 coordinator.cpp:230] Coordinator attemping to fill missing position
I0723 20:05:04.079565 9567 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I0723 20:05:04.087071 9567 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 7.490931ms
I0723 20:05:04.087090 9567 replica.cpp:676] Persisted action at 0
I0723 20:05:04.087512 9564 replica.cpp:508] Replica received write request for position 0
I0723 20:05:04.087543 9564 leveldb.cpp:438] Reading position from leveldb took 15909ns
I0723 20:05:04.095404 9564 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 7.841454ms
I0723 20:05:04.095425 9564 replica.cpp:676] Persisted action at 0
I0723 20:05:04.095743 9565 replica.cpp:655] Replica received learned notice for position 0
I0723 20:05:04.103919 9565 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 8.161443ms
I0723 20:05:04.103946 9565 replica.cpp:676] Persisted action at 0
I0723 20:05:04.103956 9565 replica.cpp:661] Replica learned NOP action at position 0
I0723 20:05:04.104517 9563 log.cpp:672] Writer started with ending position 0
I0723 20:05:04.104971 9563 leveldb.cpp:438] Reading position from leveldb took 8454ns
I0723 20:05:04.106319 9563 registrar.cpp:346] Successfully fetched the registry (0B)
I0723 20:05:04.106353 9563 registrar.cpp:422] Attempting to update the 'registry'
I0723 20:05:04.107600 9568 log.cpp:680] Attempting to append 137 bytes to the log
I0723 20:05:04.107668 9569 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I0723 20:05:04.107909 9566 replica.cpp:508] Replica received write request for position 1
I0723 20:05:05.037639 9568 hierarchical_allocator_process.hpp:724] No resources available to allocate!
I0723 20:05:05.037658 9568 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 30682ns
I0723 20:05:05.159008 9566 leveldb.cpp:343] Persisting action (156 bytes) to leveldb took 1.051069615secs
I0723 20:05:05.159070 9566 replica.cpp:676] Persisted action at 1
I0723 20:05:05.159355 9568 replica.cpp:655] Replica received learned notice for position 1
I0723 20:05:06.037797 9569 hierarchical_allocator_process.hpp:724] No resources available to allocate!
I0723 20:05:06.037819 9569 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 38065ns
I0723 20:05:06.655493 9568 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 1.496097784secs
I0723 20:05:06.655555 9568 replica.cpp:676] Persisted action at 1
I0723 20:05:06.655568 9568 replica.cpp:661] Replica learned APPEND action at position 1
I0723 20:05:06.655975 9564 registrar.cpp:479] Successfully updated 'registry'
I0723 20:05:06.656046 9564 registrar.cpp:372] Successfully recovered registrar
I0723 20:05:06.656098 9568 log.cpp:699] Attempting to truncate the log to 1
I0723 20:05:06.656183 9563 master.cpp:987] Recovered 0 slaves from the Registry (99B) ; allowing 10mins for slaves to re-register
I0723 20:05:06.656244 9569 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2
I0723 20:05:06.656580 9567 replica.cpp:508] Replica received write request for position 2
I0723 20:05:06.704031 9567 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 47.4313ms
I0723 20:05:06.704057 9567 replica.cpp:676] Persisted action at 2
I0723 20:05:06.704284 9566 replica.cpp:655] Replica received learned notice for position 2
I0723 20:05:06.716182 9566 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 11.880918ms
I0723 20:05:06.716213 9566 leveldb.cpp:401] Deleting ~1 keys from leveldb took 12794ns
I0723 20:05:06.716224 9566 replica.cpp:676] Persisted action at 2
I0723 20:05:06.716235 9566 replica.cpp:661] Replica learned TRUNCATE action at position 2
I0723 20:05:06.718736 9568 slave.cpp:169] Slave started on 1)@67.195.138.60:54168
I0723 20:05:06.718763 9568 credentials.hpp:84] Loading credential for authentication from '/tmp/MasterTest_TaskRunning_fsSHe4/credential'
I0723 20:05:06.718876 9568 slave.cpp:267] Slave using credential for: test-principal
I0723 20:05:06.721667 9531 sched.cpp:139] Version: 0.20.0
I0723 20:05:06.722085 9564 sched.cpp:235] New master detected at master@67.195.138.60:54168
I0723 20:05:06.722108 9564 sched.cpp:285] Authenticating with master master@67.195.138.60:54168
I0723 20:05:06.722231 9566 authenticatee.hpp:104] Initializing client SASL
I0723 20:05:06.806640 9566 authenticatee.hpp:128] Creating new client SASL connection
I0723 20:05:06.806992 9564 master.cpp:3504] Authenticating scheduler-833e0a77-cdea-4902-8379-50f67e10bf3d@67.195.138.60:54168
I0723 20:05:06.807200 9567 authenticator.hpp:94] Initializing server SASL
I0723 20:05:06.807420 9567 auxprop.cpp:45] Initialized in-memory auxiliary property plugin
I0723 20:05:06.807435 9567 authenticator.hpp:156] Creating new server SASL connection
I0723 20:05:06.807596 9564 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0723 20:05:06.807627 9564 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0723 20:05:06.807678 9564 authenticator.hpp:262] Received SASL authentication start
I0723 20:05:06.807728 9564 authenticator.hpp:384] Authentication requires more steps
I0723 20:05:06.807782 9564 authenticatee.hpp:265] Received SASL authentication step
I0723 20:05:06.807855 9564 authenticator.hpp:290] Received SASL authentication step
I0723 20:05:06.807883 9564 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0723 20:05:06.807898 9564 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0723 20:05:06.807914 9564 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0723 20:05:06.807929 9564 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0723 20:05:06.807939 9564 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0723 20:05:06.807945 9564 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0723 20:05:06.807962 9564 authenticator.hpp:376] Authentication success
I0723 20:05:06.808081 9564 master.cpp:3544] Successfully authenticated principal 'test-principal' at scheduler-833e0a77-cdea-4902-8379-50f67e10bf3d@67.195.138.60:54168
I0723 20:05:06.808214 9566 authenticatee.hpp:305] Authentication success
I0723 20:05:06.808281 9566 sched.cpp:359] Successfully authenticated with master master@67.195.138.60:54168
I0723 20:05:06.808298 9566 sched.cpp:478] Sending registration request to master@67.195.138.60:54168
I0723 20:05:06.808379 9566 master.cpp:1248] Received registration request from scheduler-833e0a77-cdea-4902-8379-50f67e10bf3d@67.195.138.60:54168
I0723 20:05:06.808411 9566 master.cpp:1208] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0723 20:05:06.808660 9563 master.cpp:1307] Registering framework 20140723-200504-1015726915-54168-9531-0000 at scheduler-833e0a77-cdea-4902-8379-50f67e10bf3d@67.195.138.60:54168
I0723 20:05:06.808750 9565 sched.cpp:409] Framework registered with 20140723-200504-1015726915-54168-9531-0000
I0723 20:05:06.808779 9565 sched.cpp:423] Scheduler::registered took 16553ns
I0723 20:05:06.808831 9563 hierarchical_allocator_process.hpp:331] Added framework 20140723-200504-1015726915-54168-9531-0000
I0723 20:05:06.808845 9563 hierarchical_allocator_process.hpp:724] No resources available to allocate!
I0723 20:05:06.808851 9563 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 7689ns
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build/src'>
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build/src'>
make[2]: *** [check] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build/src'>
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.20.0/_build'>
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Ubuntu-distcheck #236
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/236/changes>
Build failed in Jenkins: Mesos-Ubuntu-distcheck #235
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/235/changes>
Changes:
[dhamon] Add back declaration of stream operator.
------------------------------------------
[...truncated 60678 lines...]
I0724 02:12:33.134234 27388 replica.cpp:508] Replica received write request for position 7
I0724 02:12:33.161660 27365 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 27595us
I0724 02:12:33.161698 27365 replica.cpp:676] Persisted action at 7
I0724 02:12:33.161664 27388 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 27.41181ms
I0724 02:12:33.165663 27388 replica.cpp:676] Persisted action at 7
I0724 02:12:33.165858 27388 replica.cpp:655] Replica received learned notice for position 7
I0724 02:12:33.178020 27388 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 12.145562ms
I0724 02:12:33.178050 27388 replica.cpp:676] Persisted action at 7
I0724 02:12:33.178058 27388 replica.cpp:661] Replica learned APPEND action at position 7
I0724 02:12:33.185708 27350 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 8
I0724 02:12:33.185997 27350 replica.cpp:508] Replica received write request for position 8
I0724 02:12:33.186133 27359 replica.cpp:508] Replica received write request for position 8
I0724 02:12:33.205662 27350 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.64402ms
I0724 02:12:33.205694 27350 replica.cpp:676] Persisted action at 8
I0724 02:12:33.205802 27359 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.655615ms
I0724 02:12:33.205819 27359 replica.cpp:676] Persisted action at 8
I0724 02:12:33.206017 27359 replica.cpp:655] Replica received learned notice for position 8
I0724 02:12:33.225658 27359 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.624989ms
I0724 02:12:33.225684 27359 replica.cpp:676] Persisted action at 8
I0724 02:12:33.225693 27359 replica.cpp:661] Replica learned APPEND action at position 8
I0724 02:12:33.225889 27359 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 9
I0724 02:12:33.226167 27359 replica.cpp:508] Replica received write request for position 9
I0724 02:12:33.226258 27350 replica.cpp:508] Replica received write request for position 9
I0724 02:12:33.242336 27350 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 16.061053ms
I0724 02:12:33.242363 27350 replica.cpp:676] Persisted action at 9
I0724 02:12:33.242457 27359 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 16.27657ms
I0724 02:12:33.242478 27359 replica.cpp:676] Persisted action at 9
I0724 02:12:33.243017 27371 replica.cpp:655] Replica received learned notice for position 9
I0724 02:12:33.254402 27371 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 11.362599ms
I0724 02:12:33.254431 27371 replica.cpp:676] Persisted action at 9
I0724 02:12:33.254438 27371 replica.cpp:661] Replica learned APPEND action at position 9
I0724 02:12:33.254796 27382 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 10
I0724 02:12:33.255439 27375 replica.cpp:508] Replica received write request for position 10
I0724 02:12:33.255590 27348 replica.cpp:508] Replica received write request for position 10
I0724 02:12:33.270505 27375 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 15.04704ms
I0724 02:12:33.270509 27348 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 14.893618ms
I0724 02:12:33.270544 27375 replica.cpp:676] Persisted action at 10
I0724 02:12:33.270552 27348 replica.cpp:676] Persisted action at 10
I0724 02:12:33.271042 27352 replica.cpp:655] Replica received learned notice for position 10
I0724 02:12:33.282608 27352 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 11.548851ms
I0724 02:12:33.282635 27352 replica.cpp:676] Persisted action at 10
I0724 02:12:33.282644 27352 replica.cpp:661] Replica learned APPEND action at position 10
I0724 02:12:33.319742 27072 leveldb.cpp:176] Opened db in 36.823665ms
I0724 02:12:33.332026 27072 leveldb.cpp:183] Compacted db in 12.243361ms
I0724 02:12:33.332054 27072 leveldb.cpp:198] Created db iterator in 4416ns
I0724 02:12:33.332063 27072 leveldb.cpp:204] Seeked to beginning of db in 804ns
I0724 02:12:33.332070 27072 leveldb.cpp:273] Iterated through 0 keys in the db in 250ns
I0724 02:12:33.332087 27072 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0724 02:12:33.338079 27335 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 0
I0724 02:12:33.338112 27335 leveldb.cpp:438] Reading position from leveldb took 13038ns
I0724 02:12:33.338142 27335 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.364608 27335 catchup.cpp:247] Unable to catch-up position 1 in 10secs, retrying
I0724 02:12:33.365080 27335 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 0
I0724 02:12:33.365103 27335 leveldb.cpp:438] Reading position from leveldb took 11909ns
I0724 02:12:33.365136 27335 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 0
I0724 02:12:33.365151 27335 leveldb.cpp:438] Reading position from leveldb took 6550ns
I0724 02:12:33.365176 27335 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.378049 27338 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 2
I0724 02:12:33.378103 27338 leveldb.cpp:438] Reading position from leveldb took 23785ns
I0724 02:12:33.379734 27350 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 2
I0724 02:12:33.379760 27350 leveldb.cpp:438] Reading position from leveldb took 11421ns
I0724 02:12:33.381686 27368 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.397655 27350 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 17.88166ms
I0724 02:12:33.397696 27350 replica.cpp:676] Persisted action at 1
I0724 02:12:33.397655 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.533893ms
I0724 02:12:33.401667 27338 replica.cpp:676] Persisted action at 1
I0724 02:12:33.401881 27338 replica.cpp:655] Replica received learned notice for position 1
I0724 02:12:33.403729 27350 replica.cpp:655] Replica received learned notice for position 1
I0724 02:12:33.421653 27350 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 17.908777ms
I0724 02:12:33.421687 27350 replica.cpp:676] Persisted action at 1
I0724 02:12:33.421696 27350 replica.cpp:661] Replica learned APPEND action at position 1
I0724 02:12:33.422176 27350 replica.cpp:375] Replica received explicit promise request for position 2 with proposal 2
I0724 02:12:33.422202 27350 leveldb.cpp:438] Reading position from leveldb took 11873ns
I0724 02:12:33.421659 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.758069ms
I0724 02:12:33.425662 27338 replica.cpp:676] Persisted action at 1
I0724 02:12:33.425673 27338 replica.cpp:661] Replica learned APPEND action at position 1
I0724 02:12:33.425694 27338 replica.cpp:375] Replica received explicit promise request for position 2 with proposal 2
I0724 02:12:33.425714 27338 leveldb.cpp:438] Reading position from leveldb took 10021ns
I0724 02:12:33.425722 27374 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.440492 27350 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 18.272676ms
I0724 02:12:33.440528 27350 replica.cpp:676] Persisted action at 2
I0724 02:12:33.440492 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 14.761811ms
I0724 02:12:33.443747 27338 replica.cpp:676] Persisted action at 2
I0724 02:12:33.443954 27338 replica.cpp:655] Replica received learned notice for position 2
I0724 02:12:33.445821 27378 replica.cpp:655] Replica received learned notice for position 2
I0724 02:12:33.461658 27378 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.817365ms
I0724 02:12:33.461695 27378 replica.cpp:676] Persisted action at 2
I0724 02:12:33.461705 27378 replica.cpp:661] Replica learned APPEND action at position 2
I0724 02:12:33.462157 27378 replica.cpp:375] Replica received explicit promise request for position 3 with proposal 2
I0724 02:12:33.462182 27378 leveldb.cpp:438] Reading position from leveldb took 12282ns
I0724 02:12:33.464712 27386 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.461662 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 17.688007ms
I0724 02:12:33.465658 27338 replica.cpp:676] Persisted action at 2
I0724 02:12:33.465668 27338 replica.cpp:661] Replica learned APPEND action at position 2
I0724 02:12:33.465698 27338 replica.cpp:375] Replica received explicit promise request for position 3 with proposal 2
I0724 02:12:33.465725 27338 leveldb.cpp:438] Reading position from leveldb took 15017ns
I0724 02:12:33.481658 27378 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.458424ms
I0724 02:12:33.481688 27378 replica.cpp:676] Persisted action at 3
I0724 02:12:33.481673 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.923819ms
I0724 02:12:33.481716 27338 replica.cpp:676] Persisted action at 3
I0724 02:12:33.483916 27378 replica.cpp:655] Replica received learned notice for position 3
I0724 02:12:33.485734 27335 replica.cpp:655] Replica received learned notice for position 3
I0724 02:12:33.500849 27378 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 16.901587ms
I0724 02:12:33.500845 27335 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.085146ms
I0724 02:12:33.500875 27378 replica.cpp:676] Persisted action at 3
I0724 02:12:33.500885 27378 replica.cpp:661] Replica learned APPEND action at position 3
I0724 02:12:33.500885 27335 replica.cpp:676] Persisted action at 3
I0724 02:12:33.500895 27335 replica.cpp:661] Replica learned APPEND action at position 3
I0724 02:12:33.501440 27335 replica.cpp:375] Replica received explicit promise request for position 4 with proposal 2
I0724 02:12:33.501473 27335 leveldb.cpp:438] Reading position from leveldb took 17809ns
I0724 02:12:33.501684 27374 replica.cpp:375] Replica received explicit promise request for position 4 with proposal 2
I0724 02:12:33.501713 27374 leveldb.cpp:438] Reading position from leveldb took 12932ns
I0724 02:12:33.501765 27334 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.520961 27335 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.465336ms
I0724 02:12:33.521006 27335 replica.cpp:676] Persisted action at 4
I0724 02:12:33.521312 27335 replica.cpp:655] Replica received learned notice for position 4
I0724 02:12:33.521661 27374 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.930571ms
I0724 02:12:33.521682 27374 replica.cpp:676] Persisted action at 4
I0724 02:12:33.521728 27374 replica.cpp:655] Replica received learned notice for position 4
I0724 02:12:33.537066 27335 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.723547ms
I0724 02:12:33.537104 27335 replica.cpp:676] Persisted action at 4
I0724 02:12:33.537112 27335 replica.cpp:661] Replica learned APPEND action at position 4
I0724 02:12:33.537189 27374 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.445096ms
I0724 02:12:33.537214 27374 replica.cpp:676] Persisted action at 4
I0724 02:12:33.537225 27374 replica.cpp:661] Replica learned APPEND action at position 4
I0724 02:12:33.537967 27374 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.538142 27334 replica.cpp:375] Replica received explicit promise request for position 5 with proposal 2
I0724 02:12:33.538173 27334 leveldb.cpp:438] Reading position from leveldb took 13552ns
I0724 02:12:33.538298 27382 replica.cpp:375] Replica received explicit promise request for position 5 with proposal 2
I0724 02:12:33.538322 27382 leveldb.cpp:438] Reading position from leveldb took 10178ns
I0724 02:12:33.557158 27334 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 18.968944ms
I0724 02:12:33.557158 27382 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 18.820132ms
I0724 02:12:33.557199 27334 replica.cpp:676] Persisted action at 5
I0724 02:12:33.557203 27382 replica.cpp:676] Persisted action at 5
I0724 02:12:33.557775 27370 replica.cpp:655] Replica received learned notice for position 5
I0724 02:12:33.557802 27388 replica.cpp:655] Replica received learned notice for position 5
I0724 02:12:33.577280 27388 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.455614ms
I0724 02:12:33.577280 27370 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.47438ms
I0724 02:12:33.577345 27388 replica.cpp:676] Persisted action at 5
I0724 02:12:33.577352 27370 replica.cpp:676] Persisted action at 5
I0724 02:12:33.577353 27388 replica.cpp:661] Replica learned APPEND action at position 5
I0724 02:12:33.577360 27370 replica.cpp:661] Replica learned APPEND action at position 5
I0724 02:12:33.578269 27335 replica.cpp:375] Replica received explicit promise request for position 6 with proposal 2
I0724 02:12:33.578306 27335 leveldb.cpp:438] Reading position from leveldb took 20220ns
I0724 02:12:33.578327 27361 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.578495 27338 replica.cpp:375] Replica received explicit promise request for position 6 with proposal 2
I0724 02:12:33.578523 27338 leveldb.cpp:438] Reading position from leveldb took 11722ns
I0724 02:12:33.597409 27338 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 18.861349ms
I0724 02:12:33.597409 27335 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.077476ms
I0724 02:12:33.597477 27338 replica.cpp:676] Persisted action at 6
I0724 02:12:33.597481 27335 replica.cpp:676] Persisted action at 6
I0724 02:12:33.597965 27373 replica.cpp:655] Replica received learned notice for position 6
I0724 02:12:33.598168 27386 replica.cpp:655] Replica received learned notice for position 6
I0724 02:12:33.617506 27373 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.518997ms
I0724 02:12:33.617535 27373 replica.cpp:676] Persisted action at 6
I0724 02:12:33.617542 27373 replica.cpp:661] Replica learned APPEND action at position 6
I0724 02:12:33.617518 27386 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.322178ms
I0724 02:12:33.617566 27386 replica.cpp:676] Persisted action at 6
I0724 02:12:33.617575 27386 replica.cpp:661] Replica learned APPEND action at position 6
I0724 02:12:33.618513 27343 replica.cpp:375] Replica received explicit promise request for position 7 with proposal 2
I0724 02:12:33.618546 27343 leveldb.cpp:438] Reading position from leveldb took 13379ns
I0724 02:12:33.618681 27382 replica.cpp:375] Replica received explicit promise request for position 7 with proposal 2
I0724 02:12:33.618705 27375 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.618705 27382 leveldb.cpp:438] Reading position from leveldb took 9192ns
I0724 02:12:33.637610 27382 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 18.840785ms
I0724 02:12:33.637645 27382 replica.cpp:676] Persisted action at 7
I0724 02:12:33.637774 27343 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.213251ms
I0724 02:12:33.637801 27343 replica.cpp:676] Persisted action at 7
I0724 02:12:33.637977 27343 replica.cpp:655] Replica received learned notice for position 7
I0724 02:12:33.638125 27382 replica.cpp:655] Replica received learned notice for position 7
I0724 02:12:33.657719 27382 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.577168ms
I0724 02:12:33.657745 27382 replica.cpp:676] Persisted action at 7
I0724 02:12:33.657753 27382 replica.cpp:661] Replica learned APPEND action at position 7
I0724 02:12:33.658196 27382 replica.cpp:375] Replica received explicit promise request for position 8 with proposal 2
I0724 02:12:33.658220 27382 leveldb.cpp:438] Reading position from leveldb took 11550ns
I0724 02:12:33.658303 27371 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.658334 27343 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 20.332443ms
I0724 02:12:33.658349 27343 replica.cpp:676] Persisted action at 7
I0724 02:12:33.658355 27343 replica.cpp:661] Replica learned APPEND action at position 7
I0724 02:12:33.658375 27343 replica.cpp:375] Replica received explicit promise request for position 8 with proposal 2
I0724 02:12:33.658390 27343 leveldb.cpp:438] Reading position from leveldb took 7874ns
I0724 02:12:33.677826 27382 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 19.591399ms
I0724 02:12:33.677855 27382 replica.cpp:676] Persisted action at 8
I0724 02:12:33.677964 27343 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 19.563231ms
I0724 02:12:33.677980 27343 replica.cpp:676] Persisted action at 8
I0724 02:12:33.678174 27343 replica.cpp:655] Replica received learned notice for position 8
I0724 02:12:33.678271 27382 replica.cpp:655] Replica received learned notice for position 8
I0724 02:12:33.698366 27382 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 20.077706ms
I0724 02:12:33.698395 27382 replica.cpp:676] Persisted action at 8
I0724 02:12:33.698401 27382 replica.cpp:661] Replica learned APPEND action at position 8
I0724 02:12:33.698869 27382 replica.cpp:375] Replica received explicit promise request for position 9 with proposal 2
I0724 02:12:33.698892 27382 leveldb.cpp:438] Reading position from leveldb took 11413ns
I0724 02:12:33.699033 27359 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.699102 27343 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 20.9104ms
I0724 02:12:33.699126 27343 replica.cpp:676] Persisted action at 8
I0724 02:12:33.699132 27343 replica.cpp:661] Replica learned APPEND action at position 8
I0724 02:12:33.699153 27343 replica.cpp:375] Replica received explicit promise request for position 9 with proposal 2
I0724 02:12:33.699168 27343 leveldb.cpp:438] Reading position from leveldb took 8243ns
I0724 02:12:33.714465 27382 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 15.557817ms
I0724 02:12:33.714493 27382 replica.cpp:676] Persisted action at 9
I0724 02:12:33.714634 27343 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.453231ms
I0724 02:12:33.714658 27343 replica.cpp:676] Persisted action at 9
I0724 02:12:33.715315 27361 replica.cpp:655] Replica received learned notice for position 9
I0724 02:12:33.715347 27388 replica.cpp:655] Replica received learned notice for position 9
I0724 02:12:33.730597 27361 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.242124ms
I0724 02:12:33.730597 27388 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 15.225562ms
I0724 02:12:33.730660 27361 replica.cpp:676] Persisted action at 9
I0724 02:12:33.730665 27388 replica.cpp:676] Persisted action at 9
I0724 02:12:33.730670 27361 replica.cpp:661] Replica learned APPEND action at position 9
I0724 02:12:33.730674 27388 replica.cpp:661] Replica learned APPEND action at position 9
I0724 02:12:33.731637 27347 replica.cpp:375] Replica received explicit promise request for position 10 with proposal 2
I0724 02:12:33.731659 27386 replica.cpp:375] Replica received explicit promise request for position 10 with proposal 2
I0724 02:12:33.731674 27347 leveldb.cpp:438] Reading position from leveldb took 21411ns
I0724 02:12:33.731689 27386 leveldb.cpp:438] Reading position from leveldb took 12716ns
I0724 02:12:33.731745 27388 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status
I0724 02:12:33.742712 27386 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 11.004399ms
I0724 02:12:33.742739 27386 replica.cpp:676] Persisted action at 10
I0724 02:12:33.747957 27347 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 16.254379ms
I0724 02:12:33.748013 27347 replica.cpp:676] Persisted action at 10
I0724 02:12:33.748541 27347 replica.cpp:655] Replica received learned notice for position 10
I0724 02:12:33.748605 27388 replica.cpp:655] Replica received learned notice for position 10
I0724 02:12:33.758826 27347 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 10.262602ms
I0724 02:12:33.758826 27388 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 10.200025ms
I0724 02:12:33.758885 27347 replica.cpp:676] Persisted action at 10
I0724 02:12:33.758890 27388 replica.cpp:676] Persisted action at 10
I0724 02:12:33.758896 27347 replica.cpp:661] Replica learned APPEND action at position 10
I0724 02:12:33.758900 27388 replica.cpp:661] Replica learned APPEND action at position 10
[ OK ] RecoverTest.CatchupRetry (1391 ms)
[----------] 4 tests from RecoverTest (5825 ms total)
[----------] Global test environment tear-down
[==========] 390 tests from 64 test cases ran. (443589 ms total)
[ PASSED ] 389 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.SchedulerFailover, where TypeParam = mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 4 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build'
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure