See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2276/changes>
Changes: [dhamon] Introduce script for generating test coverage report. ------------------------------------------ [...truncated 3243 lines...] make[3]: Nothing to be done for `../../src/tests/no_executor_framework_test.sh'. make[3]: Nothing to be done for `../../src/tests/python_framework_test.sh'. make[3]: Nothing to be done for `../../src/tests/test_framework_test.sh'. make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make check-local make[3]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' ./mesos-tests WARNING: Logging before InitGoogleLogging() is written to STDERR I0723 20:41:34.180785 7602 process.cpp:1671] libprocess is initialized on 67.195.138.9:44555 for 8 cpus I0723 20:41:34.180919 7602 logging.cpp:172] Logging to STDERR Source directory: /home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui Build directory: /home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/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 5342 . [==========] Running 351 tests from 57 test cases. [----------] Global test environment set-up. [----------] 2 tests from FetcherTest [ RUN ] FetcherTest.FileURI Using temporary directory '/tmp/FetcherTest_FileURI_bsSDxW' WARNING: Logging before InitGoogleLogging() is written to STDERR I0723 20:41:36.189797 7632 fetcher.cpp:76] Fetching URI 'file:///tmp/FetcherTest_FileURI_bsSDxW/from/test' I0723 20:41:36.189939 7632 fetcher.cpp:179] Copying resource from '/tmp/FetcherTest_FileURI_bsSDxW/from/test' to '/tmp/FetcherTest_FileURI_bsSDxW' I0723 20:41:36.192847 7632 fetcher.cpp:255] Skipped extracting path '/tmp/FetcherTest_FileURI_bsSDxW/test' [ OK ] FetcherTest.FileURI (2002 ms) [ RUN ] FetcherTest.FileLocalhostURI Using temporary directory '/tmp/FetcherTest_FileLocalhostURI_jFfGNy' WARNING: Logging before InitGoogleLogging() is written to STDERR I0723 20:41:36.231168 7692 fetcher.cpp:76] Fetching URI 'file://localhost/tmp/FetcherTest_FileLocalhostURI_jFfGNy/from/test' I0723 20:41:36.231308 7692 fetcher.cpp:179] Copying resource from '/tmp/FetcherTest_FileLocalhostURI_jFfGNy/from/test' to '/tmp/FetcherTest_FileLocalhostURI_jFfGNy' I0723 20:41:36.233958 7692 fetcher.cpp:255] Skipped extracting path '/tmp/FetcherTest_FileLocalhostURI_jFfGNy/test' [ OK ] FetcherTest.FileLocalhostURI (1000 ms) [----------] 2 tests from FetcherTest (3003 ms total) [----------] 4 tests from ReplicaTest [ RUN ] ReplicaTest.Append Using temporary directory '/tmp/ReplicaTest_Append_cjXxMd' I0723 20:41:37.392856 7602 leveldb.cpp:176] Opened db in 192.76022ms I0723 20:41:37.398449 7602 leveldb.cpp:183] Compacted db in 5.537227ms I0723 20:41:37.398475 7602 leveldb.cpp:198] Created db iterator in 10742ns I0723 20:41:37.398486 7602 leveldb.cpp:204] Seeked to beginning of db in 839ns I0723 20:41:37.398493 7602 leveldb.cpp:273] Iterated through 0 keys in the db in 186ns I0723 20:41:37.398525 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:37.429993 7623 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 30.753919ms I0723 20:41:37.430142 7623 replica.cpp:320] Persisted replica status to VOTING I0723 20:41:37.521679 7602 leveldb.cpp:176] Opened db in 91.169985ms I0723 20:41:37.577837 7602 leveldb.cpp:183] Compacted db in 56.131791ms I0723 20:41:37.577857 7602 leveldb.cpp:198] Created db iterator in 5608ns I0723 20:41:37.577934 7602 leveldb.cpp:204] Seeked to beginning of db in 9861ns I0723 20:41:37.578024 7602 leveldb.cpp:273] Iterated through 1 keys in the db in 81104ns I0723 20:41:37.578038 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:37.578413 7624 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 20:41:37.633237 7624 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 54.808279ms I0723 20:41:37.633256 7624 replica.cpp:342] Persisted promised to 1 I0723 20:41:37.633610 7623 replica.cpp:508] Replica received write request for position 1 I0723 20:41:37.638181 7623 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 4.537465ms I0723 20:41:37.638202 7623 replica.cpp:676] Persisted action at 1 I0723 20:41:37.638443 7624 leveldb.cpp:438] Reading position from leveldb took 19081ns [ OK ] ReplicaTest.Append (440 ms) [ RUN ] ReplicaTest.Restore Using temporary directory '/tmp/ReplicaTest_Restore_dK6wXT' I0723 20:41:37.711782 7602 leveldb.cpp:176] Opened db in 72.581819ms I0723 20:41:37.719236 7602 leveldb.cpp:183] Compacted db in 7.415316ms I0723 20:41:37.719254 7602 leveldb.cpp:198] Created db iterator in 3073ns I0723 20:41:37.719260 7602 leveldb.cpp:204] Seeked to beginning of db in 539ns I0723 20:41:37.719265 7602 leveldb.cpp:273] Iterated through 0 keys in the db in 280ns I0723 20:41:37.719279 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:37.761906 7626 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 42.398339ms I0723 20:41:37.761929 7626 replica.cpp:320] Persisted replica status to VOTING I0723 20:41:37.871302 7602 leveldb.cpp:176] Opened db in 101.035992ms I0723 20:41:37.926657 7602 leveldb.cpp:183] Compacted db in 55.312124ms I0723 20:41:37.926679 7602 leveldb.cpp:198] Created db iterator in 6291ns I0723 20:41:37.926695 7602 leveldb.cpp:204] Seeked to beginning of db in 9038ns I0723 20:41:37.926717 7602 leveldb.cpp:273] Iterated through 1 keys in the db in 14820ns I0723 20:41:37.926733 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:37.926915 7627 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 20:41:37.974943 7627 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 48.009792ms I0723 20:41:37.974966 7627 replica.cpp:342] Persisted promised to 1 I0723 20:41:37.975383 7628 replica.cpp:508] Replica received write request for position 1 I0723 20:41:37.986176 7628 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 10.533857ms I0723 20:41:37.986376 7628 replica.cpp:676] Persisted action at 1 I0723 20:41:37.986704 7629 leveldb.cpp:438] Reading position from leveldb took 20742ns I0723 20:41:38.086813 7602 leveldb.cpp:176] Opened db in 99.961871ms I0723 20:41:38.170034 7602 leveldb.cpp:183] Compacted db in 83.194864ms I0723 20:41:38.170054 7602 leveldb.cpp:198] Created db iterator in 4336ns I0723 20:41:38.170069 7602 leveldb.cpp:204] Seeked to beginning of db in 8291ns I0723 20:41:38.170094 7602 leveldb.cpp:273] Iterated through 2 keys in the db in 17804ns I0723 20:41:38.170127 7602 replica.cpp:741] Replica recovered with log positions 0 -> 1 with 1 holes and 1 unlearned I0723 20:41:38.170207 7623 leveldb.cpp:438] Reading position from leveldb took 28326ns [ OK ] ReplicaTest.Restore (531 ms) [ RUN ] ReplicaTest.NonVoting Using temporary directory '/tmp/ReplicaTest_NonVoting_mF0gAB' I0723 20:41:38.254024 7602 leveldb.cpp:176] Opened db in 83.057624ms I0723 20:41:38.262495 7602 leveldb.cpp:183] Compacted db in 8.441628ms I0723 20:41:38.262534 7602 leveldb.cpp:198] Created db iterator in 2719ns I0723 20:41:38.262543 7602 leveldb.cpp:204] Seeked to beginning of db in 427ns I0723 20:41:38.262550 7602 leveldb.cpp:273] Iterated through 0 keys in the db in 183ns I0723 20:41:38.262567 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:38.262745 7624 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0723 20:41:38.272923 7627 replica.cpp:503] Replica ignoring write request as it is in EMPTY status [ OK ] ReplicaTest.NonVoting (113 ms) [ RUN ] ReplicaTest.Promise Using temporary directory '/tmp/ReplicaTest_Promise_zO8Yvj' I0723 20:41:38.366005 7602 leveldb.cpp:176] Opened db in 82.62378ms I0723 20:41:38.369971 7602 leveldb.cpp:183] Compacted db in 3.943546ms I0723 20:41:38.369985 7602 leveldb.cpp:198] Created db iterator in 1937ns I0723 20:41:38.369992 7602 leveldb.cpp:204] Seeked to beginning of db in 238ns I0723 20:41:38.369997 7602 leveldb.cpp:273] Iterated through 0 keys in the db in 70ns I0723 20:41:38.370007 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:38.405983 7629 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 35.727094ms I0723 20:41:38.406008 7629 replica.cpp:320] Persisted replica status to VOTING I0723 20:41:38.517551 7602 leveldb.cpp:176] Opened db in 111.263097ms I0723 20:41:38.583976 7602 leveldb.cpp:183] Compacted db in 66.398598ms I0723 20:41:38.583997 7602 leveldb.cpp:198] Created db iterator in 4577ns I0723 20:41:38.584012 7602 leveldb.cpp:204] Seeked to beginning of db in 7331ns I0723 20:41:38.584027 7602 leveldb.cpp:273] Iterated through 1 keys in the db in 8411ns I0723 20:41:38.584038 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:38.584170 7627 replica.cpp:474] Replica received implicit promise request with proposal 2 I0723 20:41:38.609194 7627 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 25.013248ms I0723 20:41:38.609211 7627 replica.cpp:342] Persisted promised to 2 I0723 20:41:38.609426 7627 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 20:41:38.609441 7627 replica.cpp:479] Replica denying promise request with proposal 1 I0723 20:41:38.609570 7627 replica.cpp:474] Replica received implicit promise request with proposal 3 I0723 20:41:38.612043 7627 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 2.456493ms I0723 20:41:38.612061 7627 replica.cpp:342] Persisted promised to 3 [ OK ] ReplicaTest.Promise (331 ms) [----------] 4 tests from ReplicaTest (1415 ms total) [----------] 12 tests from AllocatorTest/0, where TypeParam = mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter> [ RUN ] AllocatorTest/0.FrameworkReregistersFirst Using temporary directory '/tmp/AllocatorTest_0_FrameworkReregistersFirst_YGWDl2' I0723 20:41:38.729573 7602 leveldb.cpp:176] Opened db in 113.266154ms I0723 20:41:38.737884 7602 leveldb.cpp:183] Compacted db in 8.291555ms I0723 20:41:38.737901 7602 leveldb.cpp:198] Created db iterator in 2686ns I0723 20:41:38.737908 7602 leveldb.cpp:204] Seeked to beginning of db in 427ns I0723 20:41:38.737913 7602 leveldb.cpp:273] Iterated through 0 keys in the db in 60ns I0723 20:41:38.737922 7602 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 20:41:38.738248 7624 recover.cpp:425] Starting replica recovery I0723 20:41:38.738363 7624 recover.cpp:451] Replica is in EMPTY status I0723 20:41:38.738755 7628 replica.cpp:638] Replica in EMPTY status received a broadcasted recover request I0723 20:41:38.738986 7622 recover.cpp:188] Received a recover response from a replica in EMPTY status I0723 20:41:38.739104 7624 recover.cpp:542] Updating replica status to STARTING I0723 20:41:38.741025 7627 master.cpp:289] Master 20140723-204138-160088899-44555-7602 (vesta.apache.org) started on 67.195.138.9:44555 I0723 20:41:38.741078 7627 master.cpp:326] Master only allowing authenticated frameworks to register I0723 20:41:38.741092 7627 master.cpp:331] Master only allowing authenticated slaves to register I0723 20:41:38.741107 7627 credentials.hpp:36] Loading credentials for authentication from '/tmp/AllocatorTest_0_FrameworkReregistersFirst_YGWDl2/credentials' I0723 20:41:38.741339 7627 master.cpp:360] Authorization enabled I0723 20:41:38.741636 7626 master.cpp:123] No whitelist given. Advertising offers for all slaves I0723 20:41:38.742012 7626 hierarchical_allocator_process.hpp:301] Initializing hierarchical allocator process with master : master@67.195.138.9:44555 I0723 20:41:38.743131 7628 master.cpp:1129] The newly elected leader is master@67.195.138.9:44555 with id 20140723-204138-160088899-44555-7602 I0723 20:41:38.743186 7628 master.cpp:1142] Elected as the leading master! I0723 20:41:38.743197 7628 master.cpp:960] Recovering from registrar I0723 20:41:38.743340 7626 registrar.cpp:313] Recovering registrar I0723 20:41:38.792685 7622 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 53.531522ms I0723 20:41:38.792709 7622 replica.cpp:320] Persisted replica status to STARTING I0723 20:41:38.792816 7622 recover.cpp:451] Replica is in STARTING status I0723 20:41:38.793200 7627 replica.cpp:638] Replica in STARTING status received a broadcasted recover request I0723 20:41:38.793365 7622 recover.cpp:188] Received a recover response from a replica in STARTING status I0723 20:41:38.793516 7624 recover.cpp:542] Updating replica status to VOTING I0723 20:41:38.803375 7622 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 9.803524ms I0723 20:41:38.803395 7622 replica.cpp:320] Persisted replica status to VOTING I0723 20:41:38.803431 7624 recover.cpp:556] Successfully joined the Paxos group I0723 20:41:38.803519 7624 recover.cpp:440] Recover process terminated I0723 20:41:38.803635 7624 log.cpp:656] Attempting to start the writer I0723 20:41:38.804036 7626 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 20:41:38.811331 7626 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 7.280405ms I0723 20:41:38.811347 7626 replica.cpp:342] Persisted promised to 1 I0723 20:41:38.811569 7626 coordinator.cpp:230] Coordinator attemping to fill missing position I0723 20:41:38.811959 7626 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0723 20:41:38.819311 7626 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 7.340818ms I0723 20:41:38.819325 7626 replica.cpp:676] Persisted action at 0 I0723 20:41:38.819623 7626 replica.cpp:508] Replica received write request for position 0 I0723 20:41:38.819654 7626 leveldb.cpp:438] Reading position from leveldb took 11572ns I0723 20:41:38.827287 7626 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 7.617985ms I0723 20:41:38.827299 7626 replica.cpp:676] Persisted action at 0 I0723 20:41:38.827437 7626 replica.cpp:655] Replica received learned notice for position 0 I0723 20:41:38.835260 7626 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 7.81183ms I0723 20:41:38.835274 7626 replica.cpp:676] Persisted action at 0 I0723 20:41:38.835280 7626 replica.cpp:661] Replica learned NOP action at position 0 I0723 20:41:38.835441 7626 log.cpp:672] Writer started with ending position 0 I0723 20:41:38.835790 7627 leveldb.cpp:438] Reading position from leveldb took 8273ns I0723 20:41:38.836839 7627 registrar.cpp:346] Successfully fetched the registry (0B) I0723 20:41:38.836865 7627 registrar.cpp:422] Attempting to update the 'registry' I0723 20:41:38.837831 7627 log.cpp:680] Attempting to append 133 bytes to the log I0723 20:41:38.838012 7623 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0723 20:41:38.838230 7622 replica.cpp:508] Replica received write request for position 1 I0723 20:41:38.846289 7622 leveldb.cpp:343] Persisting action (152 bytes) to leveldb took 8.046888ms I0723 20:41:38.846304 7622 replica.cpp:676] Persisted action at 1 I0723 20:41:38.846472 7622 replica.cpp:655] Replica received learned notice for position 1 I0723 20:41:38.855198 7622 leveldb.cpp:343] Persisting action (154 bytes) to leveldb took 8.716858ms I0723 20:41:38.855213 7622 replica.cpp:676] Persisted action at 1 I0723 20:41:38.855219 7622 replica.cpp:661] Replica learned APPEND action at position 1 I0723 20:41:38.855494 7622 registrar.cpp:479] Successfully updated 'registry' I0723 20:41:38.855525 7622 registrar.cpp:372] Successfully recovered registrar I0723 20:41:38.855566 7622 log.cpp:699] Attempting to truncate the log to 1 I0723 20:41:38.855633 7622 master.cpp:987] Recovered 0 slaves from the Registry (96B) ; allowing 10mins for slaves to re-register I0723 20:41:38.855667 7622 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I0723 20:41:38.855885 7622 replica.cpp:508] Replica received write request for position 2 I0723 20:41:38.863175 7622 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 7.27886ms I0723 20:41:38.863189 7622 replica.cpp:676] Persisted action at 2 I0723 20:41:38.863441 7622 replica.cpp:655] Replica received learned notice for position 2 I0723 20:41:38.871152 7622 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 7.679621ms I0723 20:41:38.871196 7622 leveldb.cpp:401] Deleting ~1 keys from leveldb took 21987ns I0723 20:41:38.871211 7622 replica.cpp:676] Persisted action at 2 I0723 20:41:38.871222 7622 replica.cpp:661] Replica learned TRUNCATE action at position 2 I0723 20:41:38.878340 7625 slave.cpp:169] Slave started on 1)@67.195.138.9:44555 I0723 20:41:38.878366 7625 credentials.hpp:84] Loading credential for authentication from '/tmp/AllocatorTest_0_FrameworkReregistersFirst_pANATL/credential' I0723 20:41:38.878479 7625 slave.cpp:267] Slave using credential for: test-principal I0723 20:41:38.881353 7602 sched.cpp:139] Version: 0.20.0 I0723 20:41:38.881719 7629 sched.cpp:235] New master detected at master@67.195.138.9:44555 I0723 20:41:38.881737 7629 sched.cpp:285] Authenticating with master master@67.195.138.9:44555 I0723 20:41:38.881927 7629 authenticatee.hpp:104] Initializing client SASL I0723 20:41:38.955685 7629 authenticatee.hpp:128] Creating new client SASL connection I0723 20:41:38.955989 7626 master.cpp:3504] Authenticating scheduler-08f6ee81-f9ef-4cb0-95a5-024393f309d5@67.195.138.9:44555 I0723 20:41:38.956110 7622 authenticator.hpp:94] Initializing server SASL I0723 20:41:38.956333 7622 auxprop.cpp:45] Initialized in-memory auxiliary property plugin I0723 20:41:38.956349 7622 authenticator.hpp:156] Creating new server SASL connection I0723 20:41:38.956426 7626 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0723 20:41:38.956444 7626 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0723 20:41:38.956480 7626 authenticator.hpp:262] Received SASL authentication start I0723 20:41:38.956522 7626 authenticator.hpp:384] Authentication requires more steps I0723 20:41:38.956553 7626 authenticatee.hpp:265] Received SASL authentication step I0723 20:41:38.956614 7626 authenticator.hpp:290] Received SASL authentication step I0723 20:41:38.956635 7626 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0723 20:41:38.956645 7626 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0723 20:41:38.956660 7626 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0723 20:41:38.956670 7626 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0723 20:41:38.956677 7626 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0723 20:41:38.956683 7626 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0723 20:41:38.956696 7626 authenticator.hpp:376] Authentication success I0723 20:41:38.956723 7626 authenticatee.hpp:305] Authentication success I0723 20:41:38.956755 7626 master.cpp:3544] Successfully authenticated principal 'test-principal' at scheduler-08f6ee81-f9ef-4cb0-95a5-024393f309d5@67.195.138.9:44555 I0723 20:41:38.956847 7626 sched.cpp:359] Successfully authenticated with master master@67.195.138.9:44555 I0723 20:41:38.956864 7626 sched.cpp:478] Sending registration request to master@67.195.138.9:44555 I0723 20:41:38.956946 7622 master.cpp:1248] Received registration request from scheduler-08f6ee81-f9ef-4cb0-95a5-024393f309d5@67.195.138.9:44555 I0723 20:41:38.956977 7622 master.cpp:1208] Authorizing framework principal 'test-principal' to receive offers for role '*' I0723 20:41:38.957208 7622 master.cpp:1307] Registering framework 20140723-204138-160088899-44555-7602-0000 at scheduler-08f6ee81-f9ef-4cb0-95a5-024393f309d5@67.195.138.9:44555 I0723 20:41:38.957309 7624 sched.cpp:409] Framework registered with 20140723-204138-160088899-44555-7602-0000 I0723 20:41:38.957355 7624 sched.cpp:423] Scheduler::registered took 31333ns I0723 20:41:38.957490 7624 hierarchical_allocator_process.hpp:331] Added framework 20140723-204138-160088899-44555-7602-0000 I0723 20:41:38.957506 7624 hierarchical_allocator_process.hpp:724] No resources available to allocate! I0723 20:41:38.957516 7624 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 14041ns make[3]: *** [check-local] Segmentation fault make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure