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