You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Jojy Varghese <jo...@mesosphere.io> on 2015/11/03 06:16:44 UTC
Re: Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,ubuntu:14.04,docker||Hadoop #975
Once the refactor patches lands, I believe it will be more stable. As a result of initial investigation, https://issues.apache.org/jira/browse/MESOS-3798 <https://issues.apache.org/jira/browse/MESOS-3798> was filed. As a side effect of refactor patches, the API has changed and hence MESOS-3798 should not be seen.
-Jojy
> On Nov 2, 2015, at 7:04 PM, Benjamin Mahler <bm...@mesosphere.io> wrote:
>
> +jojy, tim
>
> What is the plan for the flaky registry client tests? Do we need to disable them?
>
> On Mon, Oct 26, 2015 at 8:49 AM, Apache Jenkins Server <jenkins@builds.apache.org <ma...@builds.apache.org>> wrote:
> See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/975/changes <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/975/changes>>
>
> Changes:
>
> [joris.van.remoortere] Clarified libevent config error messages.
>
> [joris.van.remoortere] Libprocess: Clarified libevent config error messages.
>
> ------------------------------------------
> [...truncated 138045 lines...]
> I1026 15:50:09.121683 29315 <tel:121683%2029315> replica.cpp:683] Persisted action at 1
> I1026 15:50:09.122272 29305 replica.cpp:662] Replica received learned notice for position 1 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.122647 29305 <tel:122647%2029305> leveldb.cpp:343] Persisting action (194 bytes) to leveldb took 355128ns
> I1026 15:50:09.122670 29305 replica.cpp:683] Persisted action at 1
> I1026 15:50:09.122690 29305 replica.cpp:668] Replica learned APPEND action at position 1
> I1026 15:50:09.123549 29314 registrar.cpp:486] Successfully updated the 'registry' in 3.50208ms
> I1026 15:50:09.123697 29312 log.cpp:704] Attempting to truncate the log to 1
> I1026 15:50:09.123723 29314 registrar.cpp:372] Successfully recovered registrar
> I1026 15:50:09.123795 29311 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
> I1026 15:50:09.124063 29318 master.cpp:1410] Recovered 0 slaves from the Registry (134B) ; allowing 10mins for slaves to re-register
> I1026 15:50:09.124665 29309 replica.cpp:512] Replica received write request for position 2 from (10359)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.125011 29309 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 324035ns
> I1026 15:50:09.125053 29309 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.125520 29308 replica.cpp:662] Replica received learned notice for position 2 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.125841 29308 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 305587ns
> I1026 15:50:09.125900 29308 leveldb.cpp:401] Deleting ~1 keys from leveldb took 45296ns
> I1026 15:50:09.125926 29308 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.125952 29308 replica.cpp:668] Replica learned TRUNCATE action at position 2
> I1026 15:50:09.136596 29308 process.cpp:3078] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I1026 15:50:09.136834 29308 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.6.15:34094 <http://172.17.6.15:34094/>
> I1026 15:50:09.137229 29308 master.cpp:1862] Received subscription request for HTTP framework 'default'
> I1026 15:50:09.137269 29308 master.cpp:1639] Authorizing framework principal 'test-principal' to receive offers for role '*'
> I1026 15:50:09.137732 29316 master.cpp:1954] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
> I1026 15:50:09.138190 29315 hierarchical.cpp:185] Added framework f4c2e679-a053-43b8-869e-640d11397b81-0000
> I1026 15:50:09.138468 29318 master.hpp:1436] Sending heartbeat to f4c2e679-a053-43b8-869e-640d11397b81-0000
> I1026 15:50:09.138494 29315 hierarchical.cpp:952] No resources available to allocate!
> I1026 15:50:09.138540 29315 hierarchical.cpp:1045] No inverse offers to send out!
> I1026 15:50:09.138573 29315 hierarchical.cpp:851] Performed allocation for 0 slaves in 125325ns
> I1026 15:50:09.139331 29312 master.cpp:916] Master terminating
> I1026 15:50:09.139541 29314 hierarchical.cpp:220] Removed framework f4c2e679-a053-43b8-869e-640d11397b81-0000
> [ OK ] ContentType/SchedulerHttpApiTest.NoAcceptHeader/1 (59 ms)
> [ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/0
> Using temporary directory '/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_0_ZKvJdo'
> I1026 15:50:09.148459 29287 leveldb.cpp:176] Opened db in 2.565012ms
> I1026 15:50:09.149371 29287 leveldb.cpp:183] Compacted db in 897400ns
> I1026 15:50:09.149447 29287 leveldb.cpp:198] Created db iterator in 32263ns
> I1026 15:50:09.149462 29287 leveldb.cpp:204] Seeked to beginning of db in 6765ns
> I1026 15:50:09.149471 29287 leveldb.cpp:273] Iterated through 0 keys in the db in 5110ns
> I1026 15:50:09.149521 29287 replica.cpp:748] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
> I1026 15:50:09.150048 29315 recover.cpp:449] Starting replica recovery
> I1026 15:50:09.150390 29315 recover.cpp:475] Replica is in EMPTY status
> I1026 15:50:09.151588 29317 replica.cpp:644] Replica in EMPTY status received a broadcasted recover request from (10363)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.151806 29310 recover.cpp:195] Received a recover response from a replica in EMPTY status
> I1026 15:50:09.152359 29307 recover.cpp:566] Updating replica status to STARTING
> I1026 15:50:09.153182 29313 master.cpp:367] Master 1fbc03fb-5e51-49b2-a783-95abe9bf0798 (0b7e59d85885) started on 172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.153208 29316 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 600767ns
> I1026 15:50:09.153237 29316 replica.cpp:323] Persisted replica status to STARTING
> I1026 15:50:09.153515 29305 recover.cpp:475] Replica is in STARTING status
> I1026 15:50:09.153216 29313 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_0_ZKvJdo/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_0_ZKvJdo/master" --zk_session_timeout="10secs"
> I1026 15:50:09.153605 29313 master.cpp:416] Master allowing unauthenticated frameworks to register
> I1026 15:50:09.153622 29313 master.cpp:419] Master only allowing authenticated slaves to register
> I1026 15:50:09.153633 29313 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_0_ZKvJdo/credentials'
> I1026 15:50:09.153918 29313 master.cpp:458] Using default 'crammd5' authenticator
> I1026 15:50:09.154265 29313 master.cpp:495] Authorization enabled
> I1026 15:50:09.154525 29307 replica.cpp:644] Replica in STARTING status received a broadcasted recover request from (10364)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.154556 29319 whitelist_watcher.cpp:79] No whitelist given
> I1026 15:50:09.154640 29312 hierarchical.cpp:140] Initialized hierarchical allocator process
> I1026 15:50:09.154965 29308 recover.cpp:195] Received a recover response from a replica in STARTING status
> I1026 15:50:09.155432 29316 recover.cpp:566] Updating replica status to VOTING
> I1026 15:50:09.156085 29317 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 334047ns
> I1026 15:50:09.156185 29317 replica.cpp:323] Persisted replica status to VOTING
> I1026 15:50:09.156318 29320 recover.cpp:580] Successfully joined the Paxos group
> I1026 15:50:09.156410 29309 master.cpp:1600] The newly elected leader is master@172.17.6.15:51272 <http://master@172.17.6.15:51272/> with id 1fbc03fb-5e51-49b2-a783-95abe9bf0798
> I1026 15:50:09.156450 29309 master.cpp:1613] Elected as the leading master!
> I1026 15:50:09.156461 29309 master.cpp:1373] Recovering from registrar
> I1026 15:50:09.156574 29317 registrar.cpp:309] Recovering registrar
> I1026 15:50:09.156770 29320 recover.cpp:464] Recover process terminated
> I1026 15:50:09.157238 29318 log.cpp:661] Attempting to start the writer
> I1026 15:50:09.158480 29319 replica.cpp:478] Replica received implicit promise request from (10365)@172.17.6.15:51272 <http://172.17.6.15:51272/> with proposal 1
> I1026 15:50:09.158881 29319 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 373114ns
> I1026 15:50:09.158912 29319 replica.cpp:345] Persisted promised to 1
> I1026 15:50:09.159790 29307 coordinator.cpp:231] Coordinator attempting to fill missing positions
> I1026 15:50:09.161139 29309 replica.cpp:378] Replica received explicit promise request from (10366)@172.17.6.15:51272 <http://172.17.6.15:51272/> for position 0 with proposal 2
> I1026 15:50:09.161514 29309 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 340051ns
> I1026 15:50:09.161545 29309 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.162829 29316 replica.cpp:512] Replica received write request for position 0 from (10367)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.162909 29316 leveldb.cpp:438] Reading position from leveldb took 53689ns
> I1026 15:50:09.163316 29316 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 361961ns
> I1026 15:50:09.163347 29316 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.164150 29307 replica.cpp:662] Replica received learned notice for position 0 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.164602 29307 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 427534ns
> I1026 15:50:09.164633 29307 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.164654 29307 replica.cpp:668] Replica learned NOP action at position 0
> I1026 15:50:09.165356 29309 log.cpp:677] Writer started with ending position 0
> I1026 15:50:09.166518 29312 leveldb.cpp:438] Reading position from leveldb took 62111ns
> I1026 15:50:09.167551 29317 registrar.cpp:342] Successfully fetched the registry (0B) in 10.934016ms
> I1026 15:50:09.167675 29317 registrar.cpp:441] Applied 1 operations in 48907ns; attempting to update the 'registry'
> I1026 15:50:09.168412 29318 log.cpp:685] Attempting to append 173 bytes to the log
> I1026 15:50:09.168573 29314 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
> I1026 15:50:09.169353 29312 replica.cpp:512] Replica received write request for position 1 from (10368)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.169728 29312 leveldb.cpp:343] Persisting action (192 bytes) to leveldb took 348064ns
> I1026 15:50:09.169759 29312 replica.cpp:683] Persisted action at 1
> I1026 15:50:09.170408 29305 replica.cpp:662] Replica received learned notice for position 1 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.170816 29305 leveldb.cpp:343] Persisting action (194 bytes) to leveldb took 383399ns
> I1026 15:50:09.170848 29305 replica.cpp:683] Persisted action at 1
> I1026 15:50:09.170868 29305 replica.cpp:668] Replica learned APPEND action at position 1
> I1026 15:50:09.171917 29308 registrar.cpp:486] Successfully updated the 'registry' in 4.140032ms
> I1026 15:50:09.172143 29308 registrar.cpp:372] Successfully recovered registrar
> I1026 15:50:09.172195 29320 log.cpp:704] Attempting to truncate the log to 1
> I1026 15:50:09.172581 29318 master.cpp:1410] Recovered 0 slaves from the Registry (134B) ; allowing 10mins for slaves to re-register
> I1026 15:50:09.172744 29315 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
> I1026 15:50:09.173702 29305 replica.cpp:512] Replica received write request for position 2 from (10369)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.174075 29305 <tel:174075%2029305> leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 349394ns
> I1026 15:50:09.174108 29305 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.174718 29316 replica.cpp:662] Replica received learned notice for position 2 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.175150 29316 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 412050ns
> I1026 15:50:09.175222 29316 leveldb.cpp:401] Deleting ~1 keys from leveldb took 53944ns
> I1026 15:50:09.175256 29316 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.175281 29316 replica.cpp:668] Replica learned TRUNCATE action at position 2
> I1026 15:50:09.185135 29316 process.cpp:3078] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I1026 15:50:09.185396 29316 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.6.15:34095 <http://172.17.6.15:34095/>
> I1026 15:50:09.185725 29316 <tel:185725%2029316> master.cpp:1862] Received subscription request for HTTP framework 'default'
> I1026 15:50:09.185757 29316 master.cpp:1639] Authorizing framework principal 'test-principal' to receive offers for role '*'
> I1026 15:50:09.186127 29316 master.cpp:1954] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
> I1026 15:50:09.186604 29306 hierarchical.cpp:185] Added framework 1fbc03fb-5e51-49b2-a783-95abe9bf0798-0000
> I1026 15:50:09.186666 29306 hierarchical.cpp:952] No resources available to allocate!
> I1026 15:50:09.186990 29306 hierarchical.cpp:1045] No inverse offers to send out!
> I1026 15:50:09.186851 29310 master.hpp:1436] Sending heartbeat to 1fbc03fb-5e51-49b2-a783-95abe9bf0798-0000
> I1026 15:50:09.187175 29306 hierarchical.cpp:851] Performed allocation for 0 slaves in 560358ns
> I1026 15:50:09.187793 29317 master.cpp:916] Master terminating
> I1026 15:50:09.187968 29306 hierarchical.cpp:220] Removed framework 1fbc03fb-5e51-49b2-a783-95abe9bf0798-0000
> [ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/0 (47 ms)
> [ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/1
> Using temporary directory '/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_1_slMyfo'
> I1026 15:50:09.197399 29287 leveldb.cpp:176] Opened db in 3.17674ms
> I1026 15:50:09.198770 29287 leveldb.cpp:183] Compacted db in 1.160414ms
> I1026 15:50:09.198925 29287 leveldb.cpp:198] Created db iterator in 41067ns
> I1026 15:50:09.199079 29287 leveldb.cpp:204] Seeked to beginning of db in 16851ns
> I1026 15:50:09.199214 29287 leveldb.cpp:273] Iterated through 0 keys in the db in 14391ns
> I1026 15:50:09.199372 29287 replica.cpp:748] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
> I1026 15:50:09.200392 29319 recover.cpp:449] Starting replica recovery
> I1026 15:50:09.200810 29319 recover.cpp:475] Replica is in EMPTY status
> I1026 15:50:09.203071 29310 master.cpp:367] Master 7d396337-de7a-4aef-b977-e5ca239c3538 (0b7e59d85885) started on 172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.203124 29310 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_1_slMyfo/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_1_slMyfo/master" --zk_session_timeout="10secs"
> I1026 15:50:09.203485 29310 master.cpp:416] Master allowing unauthenticated frameworks to register
> I1026 15:50:09.203500 29310 master.cpp:419] Master only allowing authenticated slaves to register
> I1026 15:50:09.203510 29310 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerHttpApiTest_DefaultAccept_1_slMyfo/credentials'
> I1026 15:50:09.203821 29310 master.cpp:458] Using default 'crammd5' authenticator
> I1026 15:50:09.203965 29310 master.cpp:495] Authorization enabled
> I1026 15:50:09.204360 29308 whitelist_watcher.cpp:79] No whitelist given
> I1026 15:50:09.204432 29313 hierarchical.cpp:140] Initialized hierarchical allocator process
> I1026 15:50:09.205739 29307 replica.cpp:644] Replica in EMPTY status received a broadcasted recover request from (10373)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.206248 29313 master.cpp:1600] The newly elected leader is master@172.17.6.15:51272 <http://master@172.17.6.15:51272/> with id 7d396337-de7a-4aef-b977-e5ca239c3538
> I1026 15:50:09.206302 29313 master.cpp:1613] Elected as the leading master!
> I1026 15:50:09.206316 29313 master.cpp:1373] Recovering from registrar
> I1026 15:50:09.206421 29307 registrar.cpp:309] Recovering registrar
> I1026 15:50:09.206563 29314 recover.cpp:195] Received a recover response from a replica in EMPTY status
> I1026 15:50:09.207274 29311 recover.cpp:566] Updating replica status to STARTING
> I1026 15:50:09.208276 29317 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 875738ns
> I1026 15:50:09.208307 29317 replica.cpp:323] Persisted replica status to STARTING
> I1026 15:50:09.208504 29310 recover.cpp:475] Replica is in STARTING status
> I1026 15:50:09.209609 29310 replica.cpp:644] Replica in STARTING status received a broadcasted recover request from (10374)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.209970 29312 recover.cpp:195] Received a recover response from a replica in STARTING status
> I1026 15:50:09.210489 29309 recover.cpp:566] Updating replica status to VOTING
> I1026 15:50:09.211079 29314 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 477897ns
> I1026 15:50:09.211110 29314 replica.cpp:323] Persisted replica status to VOTING
> I1026 15:50:09.211221 29314 recover.cpp:580] Successfully joined the Paxos group
> I1026 15:50:09.211395 29314 recover.cpp:464] Recover process terminated
> I1026 15:50:09.211818 29314 log.cpp:661] Attempting to start the writer
> I1026 15:50:09.212966 29314 replica.cpp:478] Replica received implicit promise request from (10375)@172.17.6.15:51272 <http://172.17.6.15:51272/> with proposal 1
> I1026 15:50:09.213286 29314 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 299002ns
> I1026 15:50:09.213311 29314 replica.cpp:345] Persisted promised to 1
> I1026 15:50:09.213862 29317 coordinator.cpp:231] Coordinator attempting to fill missing positions
> I1026 15:50:09.215252 29306 replica.cpp:378] Replica received explicit promise request from (10376)@172.17.6.15:51272 <http://172.17.6.15:51272/> for position 0 with proposal 2
> I1026 15:50:09.215597 29306 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 318408ns
> I1026 15:50:09.215634 29306 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.216836 29317 replica.cpp:512] Replica received write request for position 0 from (10377)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.217069 29317 leveldb.cpp:438] Reading position from leveldb took 82726ns
> I1026 15:50:09.217588 29317 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 392393ns
> I1026 15:50:09.217710 29317 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.218587 29315 replica.cpp:662] Replica received learned notice for position 0 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.219007 29315 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 367966ns
> I1026 15:50:09.219050 29315 replica.cpp:683] Persisted action at 0
> I1026 15:50:09.219080 29315 replica.cpp:668] Replica learned NOP action at position 0
> I1026 15:50:09.219849 29309 log.cpp:677] Writer started with ending position 0
> I1026 15:50:09.221317 29307 leveldb.cpp:438] Reading position from leveldb took 73333ns
> I1026 15:50:09.222697 29311 registrar.cpp:342] Successfully fetched the registry (0B) in 16.226048ms
> I1026 15:50:09.222900 29311 registrar.cpp:441] Applied 1 operations in 69057ns; attempting to update the 'registry'
> I1026 15:50:09.223919 29311 log.cpp:685] Attempting to append 173 bytes to the log
> I1026 15:50:09.224313 29307 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
> I1026 15:50:09.225236 29320 replica.cpp:512] Replica received write request for position 1 from (10378)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.225882 29320 leveldb.cpp:343] Persisting action (192 bytes) to leveldb took 605316ns
> I1026 15:50:09.225915 29320 replica.cpp:683] Persisted action at 1
> I1026 15:50:09.226804 29312 replica.cpp:662] Replica received learned notice for position 1 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.227511 29312 leveldb.cpp:343] Persisting action (194 bytes) to leveldb took 526254ns
> I1026 15:50:09.227624 29312 replica.cpp:683] Persisted action at 1
> I1026 15:50:09.227777 29312 replica.cpp:668] Replica learned APPEND action at position 1
> I1026 15:50:09.229336 29306 registrar.cpp:486] Successfully updated the 'registry' in 6.342144ms
> I1026 15:50:09.229615 29306 registrar.cpp:372] Successfully recovered registrar
> I1026 15:50:09.229658 29314 log.cpp:704] Attempting to truncate the log to 1
> I1026 15:50:09.229831 29310 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
> I1026 15:50:09.230161 29314 master.cpp:1410] Recovered 0 slaves from the Registry (134B) ; allowing 10mins for slaves to re-register
> I1026 15:50:09.231843 29309 replica.cpp:512] Replica received write request for position 2 from (10379)@172.17.6.15:51272 <http://172.17.6.15:51272/>
> I1026 15:50:09.232400 29309 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 506587ns
> I1026 15:50:09.232431 29309 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.233258 29309 replica.cpp:662] Replica received learned notice for position 2 from @0.0.0.0:0 <http://0.0.0.0:0/>
> I1026 15:50:09.233623 29309 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 349013ns
> I1026 15:50:09.233698 29309 leveldb.cpp:401] Deleting ~1 keys from leveldb took 59832ns
> I1026 15:50:09.233841 29309 replica.cpp:683] Persisted action at 2
> I1026 15:50:09.233943 29309 replica.cpp:668] Replica learned TRUNCATE action at position 2
> I1026 15:50:09.243924 29317 process.cpp:3078] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> I1026 15:50:09.244186 29317 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.6.15:34096 <http://172.17.6.15:34096/>
> I1026 15:50:09.244607 29317 master.cpp:1862] Received subscription request for HTTP framework 'default'
> I1026 15:50:09.244638 29317 master.cpp:1639] Authorizing framework principal 'test-principal' to receive offers for role '*'
> I1026 15:50:09.245185 29317 master.cpp:1954] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
> I1026 15:50:09.245703 29313 hierarchical.cpp:185] Added framework 7d396337-de7a-4aef-b977-e5ca239c3538-0000
> I1026 15:50:09.245939 29316 master.hpp:1436] Sending heartbeat to 7d396337-de7a-4aef-b977-e5ca239c3538-0000
> I1026 15:50:09.246165 29313 hierarchical.cpp:952] No resources available to allocate!
> I1026 15:50:09.246299 29313 hierarchical.cpp:1045] No inverse offers to send out!
> I1026 15:50:09.246331 29313 hierarchical.cpp:851] Performed allocation for 0 slaves in 530018ns
> I1026 15:50:09.247056 29287 master.cpp:916] Master terminating
> I1026 15:50:09.247238 29317 hierarchical.cpp:220] Removed framework 7d396337-de7a-4aef-b977-e5ca239c3538-0000
> [ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/1 (59 ms)
> [----------] 18 tests from ContentType/SchedulerHttpApiTest (1030 ms total)
>
> [----------] Global test environment tear-down
> [==========] 819 tests from 113 test cases ran. (318490 ms total)
> [ PASSED ] 818 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] RegistryClientTest.SimpleGetBlob
>
> 1 FAILED TEST
> YOU HAVE 7 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1445872983-5252
> Untagged: mesos-1445872983-5252:latest
> Deleted: 7b1d15e739c66841e2fdecef01ff0811df791b6c7a639cbc6374baf261be3e18
> Deleted: 1288e408bdcf3fa8c9a671414a2807b9d75c5c49aefec7dab0fdc4d7a8a246a6
> Deleted: f975ff9c246b52cd391c7fb5ab70f418f34d0a1c56bb6a745dfd7a2f923e9f40
> Deleted: d4ca1cafde427d6ec864eb150b1c6823d69315751acf967f2f01304b7a2859f3
> Deleted: e2206e863b246e5a8b2bf79107cf42f583d525ede66925f787b699cd8a62888f
> Deleted: 6312465a40318c806efcbb7be1ca98058ac83054b157c7340280a8af92512d9b
> Deleted: cdd117d52f890f4fe9ea031411648013ae8ae5febf0af6c4562f3afc38642893
> Deleted: 0272c2ac94b382c72c977138291decbe0936d6d5b7b0ae095ffca161cc24fbc5
> Deleted: d589ee60f016e7ed0e370847447b7ca428edfe9ebe9c27c42b16b3bb2916a54c
> Deleted: 6adedd4b40a3b192e80038132cc37e70c4b6ed82fb8412864cd3ea0147b3ba8c
> Deleted: bffcb27636966b82bf275f6a9d960468e72a5e7aeb356f3f22cd342e4d953135
> Deleted: 2ccb8dae30af6d5682ec175c15e5f033aa2a384edd6d906d30786610bb3a48d8
> Deleted: 6e8c5a761d83cb2bbcc28b175a88c5cc1bee99e7cc8e52b25125480fe289c772
> Build step 'Execute shell' marked build as failure
>