You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/11/07 02:22:04 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1188
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1188/changes>
Changes:
[kapil] Include ContainerInfo in command ExecutorInfo
[tnachen] Fixed some style issues in docker containerizer and tests.
[tnachen] Added Docker Image type to Container Image for v1 API.
[tnachen] Fixed Rootfs in tests to handle symlink directories.
[yujie.jay] Fixed a few style issues in slave tests.
------------------------------------------
[...truncated 137499 lines...]
I1107 01:21:47.398954 32126 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 01:21:47.399894 32118 replica.cpp:540] Replica received write request for position 1 from (10539)@172.17.15.185:38584
I1107 01:21:47.400435 32118 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 498086ns
I1107 01:21:47.400465 32118 replica.cpp:715] Persisted action at 1
I1107 01:21:47.401234 32126 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:21:47.401650 32126 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 378129ns
I1107 01:21:47.401679 32126 replica.cpp:715] Persisted action at 1
I1107 01:21:47.401701 32126 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:21:47.402725 32123 registrar.cpp:486] Successfully updated the 'registry' in 4.62592ms
I1107 01:21:47.402889 32123 registrar.cpp:372] Successfully recovered registrar
I1107 01:21:47.402966 32118 log.cpp:704] Attempting to truncate the log to 1
I1107 01:21:47.403102 32120 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:21:47.403282 32127 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:21:47.404186 32114 replica.cpp:540] Replica received write request for position 2 from (10540)@172.17.15.185:38584
I1107 01:21:47.404640 32114 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 411436ns
I1107 01:21:47.404671 32114 replica.cpp:715] Persisted action at 2
I1107 01:21:47.405432 32123 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:21:47.406003 32123 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 450822ns
I1107 01:21:47.406075 32123 leveldb.cpp:401] Deleting ~1 keys from leveldb took 40024ns
I1107 01:21:47.406100 32123 replica.cpp:715] Persisted action at 2
I1107 01:21:47.406123 32123 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:21:47.415525 32117 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:21:47.415778 32117 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.185:57277
I1107 01:21:47.416223 32117 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:21:47.416295 32117 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:21:47.416548 32117 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:21:47.417006 32122 master.hpp:1439] Sending heartbeat to e5ece224-5f8f-4fd7-aa7d-b7ae0d6c66a4-0000
I1107 01:21:47.417007 32128 hierarchical.cpp:185] Added framework e5ece224-5f8f-4fd7-aa7d-b7ae0d6c66a4-0000
I1107 01:21:47.417104 32128 hierarchical.cpp:952] No resources available to allocate!
I1107 01:21:47.417139 32128 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:21:47.417160 32128 hierarchical.cpp:851] Performed allocation for 0 slaves in 90070ns
I1107 01:21:47.418766 32123 master.cpp:922] Master terminating
I1107 01:21:47.418978 32117 hierarchical.cpp:220] Removed framework e5ece224-5f8f-4fd7-aa7d-b7ae0d6c66a4-0000
[ OK ] ContentType/SchedulerHttpApiTest.NoAcceptHeader/1 (51 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/0
I1107 01:21:47.428360 32094 leveldb.cpp:176] Opened db in 3.024954ms
I1107 01:21:47.429369 32094 leveldb.cpp:183] Compacted db in 965376ns
I1107 01:21:47.429422 32094 leveldb.cpp:198] Created db iterator in 16934ns
I1107 01:21:47.429443 32094 leveldb.cpp:204] Seeked to beginning of db in 1748ns
I1107 01:21:47.429455 32094 leveldb.cpp:273] Iterated through 0 keys in the db in 280ns
I1107 01:21:47.429492 32094 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 01:21:47.429986 32122 recover.cpp:449] Starting replica recovery
I1107 01:21:47.430328 32122 recover.cpp:475] Replica is in EMPTY status
I1107 01:21:47.431293 32121 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10544)@172.17.15.185:38584
I1107 01:21:47.431897 32118 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 01:21:47.432526 32121 recover.cpp:566] Updating replica status to STARTING
I1107 01:21:47.433303 32114 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 549225ns
I1107 01:21:47.433337 32114 replica.cpp:323] Persisted replica status to STARTING
I1107 01:21:47.433428 32127 master.cpp:367] Master 4d9926cf-b28a-4845-8e22-e1be5f727175 (4b19ea60c66f) started on 172.17.15.185:38584
I1107 01:21:47.433650 32115 recover.cpp:475] Replica is in STARTING status
I1107 01:21:47.433457 32127 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ZrjSuS/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/ZrjSuS/master" --zk_session_timeout="10secs"
I1107 01:21:47.433902 32127 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 01:21:47.433925 32127 master.cpp:419] Master only allowing authenticated slaves to register
I1107 01:21:47.433934 32127 credentials.hpp:37] Loading credentials for authentication from '/tmp/ZrjSuS/credentials'
I1107 01:21:47.434288 32127 master.cpp:458] Using default 'crammd5' authenticator
I1107 01:21:47.434417 32127 master.cpp:495] Authorization enabled
I1107 01:21:47.434612 32118 whitelist_watcher.cpp:79] No whitelist given
I1107 01:21:47.434799 32117 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10545)@172.17.15.185:38584
I1107 01:21:47.434934 32116 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 01:21:47.435319 32128 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 01:21:47.435971 32119 recover.cpp:566] Updating replica status to VOTING
I1107 01:21:47.436574 32126 master.cpp:1606] The newly elected leader is master@172.17.15.185:38584 with id 4d9926cf-b28a-4845-8e22-e1be5f727175
I1107 01:21:47.436609 32126 master.cpp:1619] Elected as the leading master!
I1107 01:21:47.436626 32126 master.cpp:1379] Recovering from registrar
I1107 01:21:47.436617 32115 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 362182ns
I1107 01:21:47.436653 32115 replica.cpp:323] Persisted replica status to VOTING
I1107 01:21:47.436779 32113 registrar.cpp:309] Recovering registrar
I1107 01:21:47.437144 32124 recover.cpp:580] Successfully joined the Paxos group
I1107 01:21:47.437919 32124 recover.cpp:464] Recover process terminated
I1107 01:21:47.438103 32121 log.cpp:661] Attempting to start the writer
I1107 01:21:47.439389 32116 replica.cpp:496] Replica received implicit promise request from (10546)@172.17.15.185:38584 with proposal 1
I1107 01:21:47.439893 32116 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 458997ns
I1107 01:21:47.439924 32116 replica.cpp:345] Persisted promised to 1
I1107 01:21:47.440640 32116 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 01:21:47.442059 32115 replica.cpp:391] Replica received explicit promise request from (10547)@172.17.15.185:38584 for position 0 with proposal 2
I1107 01:21:47.442517 32115 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 405133ns
I1107 01:21:47.442551 32115 replica.cpp:715] Persisted action at 0
I1107 01:21:47.443884 32119 replica.cpp:540] Replica received write request for position 0 from (10548)@172.17.15.185:38584
I1107 01:21:47.443967 32119 leveldb.cpp:438] Reading position from leveldb took 37241ns
I1107 01:21:47.444430 32119 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 402088ns
I1107 01:21:47.444460 32119 replica.cpp:715] Persisted action at 0
I1107 01:21:47.445225 32118 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 01:21:47.445744 32118 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 474244ns
I1107 01:21:47.445777 32118 replica.cpp:715] Persisted action at 0
I1107 01:21:47.445811 32118 replica.cpp:700] Replica learned NOP action at position 0
I1107 01:21:47.446617 32114 log.cpp:677] Writer started with ending position 0
I1107 01:21:47.447839 32120 leveldb.cpp:438] Reading position from leveldb took 34117ns
I1107 01:21:47.448998 32116 registrar.cpp:342] Successfully fetched the registry (0B) in 12.126976ms
I1107 01:21:47.449132 32116 registrar.cpp:441] Applied 1 operations in 28719ns; attempting to update the 'registry'
I1107 01:21:47.450006 32128 log.cpp:685] Attempting to append 178 bytes to the log
I1107 01:21:47.450142 32122 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 01:21:47.451299 32123 replica.cpp:540] Replica received write request for position 1 from (10549)@172.17.15.185:38584
I1107 01:21:47.451848 32123 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 499319ns
I1107 01:21:47.451879 32123 replica.cpp:715] Persisted action at 1
I1107 01:21:47.452639 32122 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:21:47.453164 32122 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 480644ns
I1107 01:21:47.453197 32122 replica.cpp:715] Persisted action at 1
I1107 01:21:47.453219 32122 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:21:47.454447 32119 registrar.cpp:486] Successfully updated the 'registry' in 5.228032ms
I1107 01:21:47.454644 32119 registrar.cpp:372] Successfully recovered registrar
I1107 01:21:47.454895 32126 log.cpp:704] Attempting to truncate the log to 1
I1107 01:21:47.455060 32116 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:21:47.455294 32127 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:21:47.456189 32115 replica.cpp:540] Replica received write request for position 2 from (10550)@172.17.15.185:38584
I1107 01:21:47.456751 32115 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 519225ns
I1107 01:21:47.456782 32115 replica.cpp:715] Persisted action at 2
I1107 01:21:47.457540 32115 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:21:47.458112 32115 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 527170ns
I1107 01:21:47.458200 32115 leveldb.cpp:401] Deleting ~1 keys from leveldb took 54811ns
I1107 01:21:47.458233 32115 replica.cpp:715] Persisted action at 2
I1107 01:21:47.458271 32115 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:21:47.467628 32123 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:21:47.467872 32123 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.185:57278
I1107 01:21:47.468070 32123 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:21:47.468143 32123 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:21:47.468523 32123 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:21:47.468958 32121 hierarchical.cpp:185] Added framework 4d9926cf-b28a-4845-8e22-e1be5f727175-0000
I1107 01:21:47.469020 32121 hierarchical.cpp:952] No resources available to allocate!
I1107 01:21:47.469069 32121 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:21:47.469099 32121 hierarchical.cpp:851] Performed allocation for 0 slaves in 108237ns
I1107 01:21:47.469213 32120 master.hpp:1439] Sending heartbeat to 4d9926cf-b28a-4845-8e22-e1be5f727175-0000
I1107 01:21:47.470270 32114 master.cpp:922] Master terminating
I1107 01:21:47.470422 32128 hierarchical.cpp:220] Removed framework 4d9926cf-b28a-4845-8e22-e1be5f727175-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/0 (52 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/1
I1107 01:21:47.480271 32094 leveldb.cpp:176] Opened db in 3.108865ms
I1107 01:21:47.481515 32094 leveldb.cpp:183] Compacted db in 1.199912ms
I1107 01:21:47.481575 32094 leveldb.cpp:198] Created db iterator in 14857ns
I1107 01:21:47.481597 32094 leveldb.cpp:204] Seeked to beginning of db in 1760ns
I1107 01:21:47.481609 32094 leveldb.cpp:273] Iterated through 0 keys in the db in 162ns
I1107 01:21:47.481644 32094 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 01:21:47.482071 32127 recover.cpp:449] Starting replica recovery
I1107 01:21:47.482357 32126 recover.cpp:475] Replica is in EMPTY status
I1107 01:21:47.483551 32118 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10554)@172.17.15.185:38584
I1107 01:21:47.484030 32114 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 01:21:47.484555 32117 recover.cpp:566] Updating replica status to STARTING
I1107 01:21:47.485242 32122 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 498501ns
I1107 01:21:47.485273 32122 replica.cpp:323] Persisted replica status to STARTING
I1107 01:21:47.485510 32122 recover.cpp:475] Replica is in STARTING status
I1107 01:21:47.486019 32123 master.cpp:367] Master 770e247b-ba1b-4b8c-9e70-cb7d75a0988f (4b19ea60c66f) started on 172.17.15.185:38584
I1107 01:21:47.486053 32123 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/RYg4yT/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/RYg4yT/master" --zk_session_timeout="10secs"
I1107 01:21:47.486376 32123 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 01:21:47.486392 32123 master.cpp:419] Master only allowing authenticated slaves to register
I1107 01:21:47.486408 32123 credentials.hpp:37] Loading credentials for authentication from '/tmp/RYg4yT/credentials'
I1107 01:21:47.486701 32123 master.cpp:458] Using default 'crammd5' authenticator
I1107 01:21:47.486786 32124 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10555)@172.17.15.185:38584
I1107 01:21:47.486901 32123 master.cpp:495] Authorization enabled
I1107 01:21:47.487130 32119 whitelist_watcher.cpp:79] No whitelist given
I1107 01:21:47.487169 32120 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 01:21:47.487308 32124 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 01:21:47.487978 32120 recover.cpp:566] Updating replica status to VOTING
I1107 01:21:47.488569 32127 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 371334ns
I1107 01:21:47.488606 32127 replica.cpp:323] Persisted replica status to VOTING
I1107 01:21:47.488759 32118 recover.cpp:580] Successfully joined the Paxos group
I1107 01:21:47.489264 32118 recover.cpp:464] Recover process terminated
I1107 01:21:47.489401 32123 master.cpp:1606] The newly elected leader is master@172.17.15.185:38584 with id 770e247b-ba1b-4b8c-9e70-cb7d75a0988f
I1107 01:21:47.489542 32123 master.cpp:1619] Elected as the leading master!
I1107 01:21:47.489579 32123 master.cpp:1379] Recovering from registrar
I1107 01:21:47.489871 32116 registrar.cpp:309] Recovering registrar
I1107 01:21:47.490561 32122 log.cpp:661] Attempting to start the writer
I1107 01:21:47.491992 32119 replica.cpp:496] Replica received implicit promise request from (10556)@172.17.15.185:38584 with proposal 1
I1107 01:21:47.492472 32119 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 435010ns
I1107 01:21:47.492502 32119 replica.cpp:345] Persisted promised to 1
I1107 01:21:47.493165 32116 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 01:21:47.494501 32125 replica.cpp:391] Replica received explicit promise request from (10557)@172.17.15.185:38584 for position 0 with proposal 2
I1107 01:21:47.494938 32125 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 382868ns
I1107 01:21:47.494978 32125 replica.cpp:715] Persisted action at 0
I1107 01:21:47.496152 32116 replica.cpp:540] Replica received write request for position 0 from (10558)@172.17.15.185:38584
I1107 01:21:47.496224 32116 leveldb.cpp:438] Reading position from leveldb took 28844ns
I1107 01:21:47.496597 32116 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 322134ns
I1107 01:21:47.496626 32116 replica.cpp:715] Persisted action at 0
I1107 01:21:47.497392 32128 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 01:21:47.497803 32128 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 357662ns
I1107 01:21:47.497851 32128 replica.cpp:715] Persisted action at 0
I1107 01:21:47.497872 32128 replica.cpp:700] Replica learned NOP action at position 0
I1107 01:21:47.498492 32124 log.cpp:677] Writer started with ending position 0
I1107 01:21:47.499578 32121 leveldb.cpp:438] Reading position from leveldb took 33991ns
I1107 01:21:47.500550 32124 registrar.cpp:342] Successfully fetched the registry (0B) in 10.621952ms
I1107 01:21:47.500679 32124 registrar.cpp:441] Applied 1 operations in 27414ns; attempting to update the 'registry'
I1107 01:21:47.501494 32119 log.cpp:685] Attempting to append 178 bytes to the log
I1107 01:21:47.501627 32114 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 01:21:47.502408 32120 replica.cpp:540] Replica received write request for position 1 from (10559)@172.17.15.185:38584
I1107 01:21:47.502907 32120 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 451205ns
I1107 01:21:47.502940 32120 replica.cpp:715] Persisted action at 1
I1107 01:21:47.503715 32119 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:21:47.504173 32119 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 421970ns
I1107 01:21:47.504205 32119 replica.cpp:715] Persisted action at 1
I1107 01:21:47.504233 32119 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:21:47.505280 32122 registrar.cpp:486] Successfully updated the 'registry' in 4.530176ms
I1107 01:21:47.505470 32122 registrar.cpp:372] Successfully recovered registrar
I1107 01:21:47.505533 32123 log.cpp:704] Attempting to truncate the log to 1
I1107 01:21:47.505973 32124 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:21:47.506000 32127 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:21:47.506943 32113 replica.cpp:540] Replica received write request for position 2 from (10560)@172.17.15.185:38584
I1107 01:21:47.507439 32113 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 450090ns
I1107 01:21:47.507472 32113 replica.cpp:715] Persisted action at 2
I1107 01:21:47.508363 32116 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:21:47.508797 32116 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 392571ns
I1107 01:21:47.508884 32116 leveldb.cpp:401] Deleting ~1 keys from leveldb took 41384ns
I1107 01:21:47.508913 32116 replica.cpp:715] Persisted action at 2
I1107 01:21:47.508935 32116 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:21:47.518136 32119 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:21:47.518385 32119 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.185:57279
I1107 01:21:47.518807 32119 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:21:47.518939 32119 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:21:47.519253 32119 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:21:47.519618 32124 hierarchical.cpp:185] Added framework 770e247b-ba1b-4b8c-9e70-cb7d75a0988f-0000
I1107 01:21:47.519673 32124 hierarchical.cpp:952] No resources available to allocate!
I1107 01:21:47.519748 32124 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:21:47.519780 32119 master.hpp:1439] Sending heartbeat to 770e247b-ba1b-4b8c-9e70-cb7d75a0988f-0000
I1107 01:21:47.520037 32124 hierarchical.cpp:851] Performed allocation for 0 slaves in 387043ns
I1107 01:21:47.521160 32118 master.cpp:922] Master terminating
I1107 01:21:47.521330 32113 hierarchical.cpp:220] Removed framework 770e247b-ba1b-4b8c-9e70-cb7d75a0988f-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/1 (50 ms)
[----------] 20 tests from ContentType/SchedulerHttpApiTest (1099 ms total)
[----------] Global test environment tear-down
[==========] 831 tests from 113 test cases ran. (343748 ms total)
[ PASSED ] 830 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] RegistryClientTest.BadRequest
1 FAILED TEST
YOU HAVE 8 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-1446857631-12247
Untagged: mesos-1446857631-12247:latest
Deleted: 9377ef9402c422d116e6fb998cccccaa7b64b92734b65fa4e203a910834a1f03
Deleted: 43a09b560fcb8265900b2802cd0c8a8532adf51e6fd085ddf6f75271749ef26e
Deleted: b626f5bd08870cacc5d6054ef20a637a5f06f722776b440c0b840fec90c36190
Deleted: 15878da2ba2e8bb8caa93a0adec85164f6d64314158631859a77df8a4640b857
Deleted: fefb555ece97d17fd05afb93ba4a7479f183c33f2262932ecee979820986cf69
Deleted: 4889c9d3e78b3156adf4050abaf90c294c73ed64076ae32264e9e02144a07f80
Deleted: bd8953db4d88db3fcb9f218c60d59c2ccd466ba55475ad069486da5d2651f194
Deleted: b58b29927b04b7d4094cd99d866353586f46ec6ad831c9fe77bb6e3b5f76bfb7
Deleted: 66d3be652b2b19000ec117e0bb6d86156ca659038a926ea2dd5cafa535f2a064
Deleted: 2a8dbcb2d1d1a513c7ae8a3bea58c67820e5788e48d16e79dadeeaf6b2962ca7
Deleted: 170e4b7b4fc1f9455aa93c20562a0f9480b28281de56dd96c59d3e4eb28cd93b
Deleted: d7c054ae0f14bfdfb1de4bc43d788db49efafa4be7289c4145f638028d18ecd6
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1190
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1190/changes>
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1189
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1189/changes>
Changes:
[yujie.jay] TemporaryDirectoryTest Merge: Elevate `TemporaryDirectoryTest::sandbox`
[yujie.jay] TemporaryDirectoryTest Merge: Point tests to the Stout version of the
[yujie.jay] TemporaryDirectoryTest Merge: Remove non-Stout version of
------------------------------------------
[...truncated 137405 lines...]
I1107 02:07:01.582159 32117 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 02:07:01.582792 32117 replica.cpp:540] Replica received write request for position 1 from (10556)@172.17.11.150:48396
I1107 02:07:01.583132 32117 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 299804ns
I1107 02:07:01.583158 32117 replica.cpp:715] Persisted action at 1
I1107 02:07:01.583700 32114 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:07:01.584044 32114 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 316336ns
I1107 02:07:01.584067 32114 replica.cpp:715] Persisted action at 1
I1107 02:07:01.584082 32114 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:07:01.584844 32122 registrar.cpp:486] Successfully updated the 'registry' in 3.366912ms
I1107 02:07:01.584975 32122 registrar.cpp:372] Successfully recovered registrar
I1107 02:07:01.585083 32116 log.cpp:704] Attempting to truncate the log to 1
I1107 02:07:01.585325 32119 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:07:01.585413 32125 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:07:01.586171 32115 replica.cpp:540] Replica received write request for position 2 from (10557)@172.17.11.150:48396
I1107 02:07:01.586549 32115 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 345549ns
I1107 02:07:01.586573 32115 replica.cpp:715] Persisted action at 2
I1107 02:07:01.587200 32115 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:07:01.587546 32115 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 316231ns
I1107 02:07:01.587595 32115 leveldb.cpp:401] Deleting ~1 keys from leveldb took 24672ns
I1107 02:07:01.587618 32115 replica.cpp:715] Persisted action at 2
I1107 02:07:01.587635 32115 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:07:01.597060 32123 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:07:01.597244 32123 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.150:49399
I1107 02:07:01.597546 32123 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:07:01.597615 32123 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:07:01.597854 32123 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:07:01.598145 32112 hierarchical.cpp:185] Added framework 22a97a2c-e360-4c94-a2fa-cb747636466f-0000
I1107 02:07:01.598189 32112 hierarchical.cpp:952] No resources available to allocate!
I1107 02:07:01.598224 32112 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:07:01.598289 32123 master.hpp:1439] Sending heartbeat to 22a97a2c-e360-4c94-a2fa-cb747636466f-0000
I1107 02:07:01.598247 32112 hierarchical.cpp:851] Performed allocation for 0 slaves in 79222ns
I1107 02:07:01.599274 32092 master.cpp:922] Master terminating
I1107 02:07:01.599527 32112 hierarchical.cpp:220] Removed framework 22a97a2c-e360-4c94-a2fa-cb747636466f-0000
[ OK ] ContentType/SchedulerHttpApiTest.NoAcceptHeader/1 (45 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/0
I1107 02:07:01.606117 32092 leveldb.cpp:176] Opened db in 2.24937ms
I1107 02:07:01.606941 32092 leveldb.cpp:183] Compacted db in 794301ns
I1107 02:07:01.606983 32092 leveldb.cpp:198] Created db iterator in 13658ns
I1107 02:07:01.607002 32092 leveldb.cpp:204] Seeked to beginning of db in 1774ns
I1107 02:07:01.607015 32092 leveldb.cpp:273] Iterated through 0 keys in the db in 284ns
I1107 02:07:01.607055 32092 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 02:07:01.607738 32115 recover.cpp:449] Starting replica recovery
I1107 02:07:01.608111 32115 recover.cpp:475] Replica is in EMPTY status
I1107 02:07:01.609539 32111 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10561)@172.17.11.150:48396
I1107 02:07:01.610039 32118 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 02:07:01.610551 32111 recover.cpp:566] Updating replica status to STARTING
I1107 02:07:01.611392 32118 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 550678ns
I1107 02:07:01.611424 32118 replica.cpp:323] Persisted replica status to STARTING
I1107 02:07:01.611724 32117 recover.cpp:475] Replica is in STARTING status
I1107 02:07:01.612066 32118 master.cpp:367] Master ee70946b-6e28-4646-b7c7-0df3f5a2f1c7 (5392456e4e79) started on 172.17.11.150:48396
I1107 02:07:01.612202 32118 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/gVmIBR/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/gVmIBR/master" --zk_session_timeout="10secs"
I1107 02:07:01.612576 32118 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 02:07:01.612593 32118 master.cpp:419] Master only allowing authenticated slaves to register
I1107 02:07:01.612607 32118 credentials.hpp:37] Loading credentials for authentication from '/tmp/gVmIBR/credentials'
I1107 02:07:01.612762 32111 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10562)@172.17.11.150:48396
I1107 02:07:01.612932 32118 master.cpp:458] Using default 'crammd5' authenticator
I1107 02:07:01.613093 32118 master.cpp:495] Authorization enabled
I1107 02:07:01.613206 32121 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 02:07:01.613359 32119 whitelist_watcher.cpp:79] No whitelist given
I1107 02:07:01.613385 32117 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 02:07:01.613677 32123 recover.cpp:566] Updating replica status to VOTING
I1107 02:07:01.614204 32126 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 275016ns
I1107 02:07:01.614231 32126 replica.cpp:323] Persisted replica status to VOTING
I1107 02:07:01.614382 32123 recover.cpp:580] Successfully joined the Paxos group
I1107 02:07:01.614586 32123 recover.cpp:464] Recover process terminated
I1107 02:07:01.615123 32118 master.cpp:1606] The newly elected leader is master@172.17.11.150:48396 with id ee70946b-6e28-4646-b7c7-0df3f5a2f1c7
I1107 02:07:01.615156 32118 master.cpp:1619] Elected as the leading master!
I1107 02:07:01.615180 32118 master.cpp:1379] Recovering from registrar
I1107 02:07:01.615356 32112 registrar.cpp:309] Recovering registrar
I1107 02:07:01.615774 32118 log.cpp:661] Attempting to start the writer
I1107 02:07:01.616796 32117 replica.cpp:496] Replica received implicit promise request from (10563)@172.17.11.150:48396 with proposal 1
I1107 02:07:01.617138 32117 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 313036ns
I1107 02:07:01.617161 32117 replica.cpp:345] Persisted promised to 1
I1107 02:07:01.617710 32120 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 02:07:01.618717 32119 replica.cpp:391] Replica received explicit promise request from (10564)@172.17.11.150:48396 for position 0 with proposal 2
I1107 02:07:01.619032 32119 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 280294ns
I1107 02:07:01.619055 32119 replica.cpp:715] Persisted action at 0
I1107 02:07:01.619943 32117 replica.cpp:540] Replica received write request for position 0 from (10565)@172.17.11.150:48396
I1107 02:07:01.620007 32117 leveldb.cpp:438] Reading position from leveldb took 27697ns
I1107 02:07:01.620331 32117 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 279875ns
I1107 02:07:01.620353 32117 replica.cpp:715] Persisted action at 0
I1107 02:07:01.620882 32120 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 02:07:01.621201 32120 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 289123ns
I1107 02:07:01.621227 32120 replica.cpp:715] Persisted action at 0
I1107 02:07:01.621250 32120 replica.cpp:700] Replica learned NOP action at position 0
I1107 02:07:01.621748 32123 log.cpp:677] Writer started with ending position 0
I1107 02:07:01.622661 32112 leveldb.cpp:438] Reading position from leveldb took 21721ns
I1107 02:07:01.623414 32112 registrar.cpp:342] Successfully fetched the registry (0B) in 8.012032ms
I1107 02:07:01.623512 32112 registrar.cpp:441] Applied 1 operations in 20329ns; attempting to update the 'registry'
I1107 02:07:01.624096 32123 log.cpp:685] Attempting to append 178 bytes to the log
I1107 02:07:01.624236 32115 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 02:07:01.624930 32125 replica.cpp:540] Replica received write request for position 1 from (10566)@172.17.11.150:48396
I1107 02:07:01.625247 32125 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 278119ns
I1107 02:07:01.625283 32125 replica.cpp:715] Persisted action at 1
I1107 02:07:01.625885 32123 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:07:01.626284 32123 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 368209ns
I1107 02:07:01.626307 32123 replica.cpp:715] Persisted action at 1
I1107 02:07:01.626322 32123 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:07:01.627094 32123 registrar.cpp:486] Successfully updated the 'registry' in 3.526912ms
I1107 02:07:01.627225 32123 registrar.cpp:372] Successfully recovered registrar
I1107 02:07:01.627302 32113 log.cpp:704] Attempting to truncate the log to 1
I1107 02:07:01.627415 32111 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:07:01.627648 32118 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:07:01.628204 32118 replica.cpp:540] Replica received write request for position 2 from (10567)@172.17.11.150:48396
I1107 02:07:01.628566 32118 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 326163ns
I1107 02:07:01.628590 32118 replica.cpp:715] Persisted action at 2
I1107 02:07:01.629107 32118 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:07:01.629245 32118 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 109951ns
I1107 02:07:01.629309 32118 leveldb.cpp:401] Deleting ~1 keys from leveldb took 29734ns
I1107 02:07:01.629335 32118 replica.cpp:715] Persisted action at 2
I1107 02:07:01.629361 32118 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:07:01.640015 32111 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:07:01.640211 32111 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.150:49400
I1107 02:07:01.640442 32111 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:07:01.640517 32111 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:07:01.640844 32111 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:07:01.641237 32114 hierarchical.cpp:185] Added framework ee70946b-6e28-4646-b7c7-0df3f5a2f1c7-0000
I1107 02:07:01.641324 32114 hierarchical.cpp:952] No resources available to allocate!
I1107 02:07:01.641461 32112 master.hpp:1439] Sending heartbeat to ee70946b-6e28-4646-b7c7-0df3f5a2f1c7-0000
I1107 02:07:01.641533 32114 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:07:01.641563 32114 hierarchical.cpp:851] Performed allocation for 0 slaves in 275298ns
I1107 02:07:01.643187 32123 master.cpp:922] Master terminating
I1107 02:07:01.643460 32113 hierarchical.cpp:220] Removed framework ee70946b-6e28-4646-b7c7-0df3f5a2f1c7-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/0 (44 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/1
I1107 02:07:01.649695 32092 leveldb.cpp:176] Opened db in 1.842846ms
I1107 02:07:01.650320 32092 leveldb.cpp:183] Compacted db in 593539ns
I1107 02:07:01.650358 32092 leveldb.cpp:198] Created db iterator in 12498ns
I1107 02:07:01.650378 32092 leveldb.cpp:204] Seeked to beginning of db in 1664ns
I1107 02:07:01.650389 32092 leveldb.cpp:273] Iterated through 0 keys in the db in 199ns
I1107 02:07:01.650426 32092 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 02:07:01.650758 32122 recover.cpp:449] Starting replica recovery
I1107 02:07:01.651121 32111 recover.cpp:475] Replica is in EMPTY status
I1107 02:07:01.652320 32116 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10571)@172.17.11.150:48396
I1107 02:07:01.652583 32116 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 02:07:01.652966 32112 master.cpp:367] Master c0e821b3-2504-4653-baae-767323044085 (5392456e4e79) started on 172.17.11.150:48396
I1107 02:07:01.653040 32118 recover.cpp:566] Updating replica status to STARTING
I1107 02:07:01.653105 32112 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/AasxgD/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/AasxgD/master" --zk_session_timeout="10secs"
I1107 02:07:01.653484 32112 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 02:07:01.653501 32112 master.cpp:419] Master only allowing authenticated slaves to register
I1107 02:07:01.653511 32112 credentials.hpp:37] Loading credentials for authentication from '/tmp/AasxgD/credentials'
I1107 02:07:01.653569 32119 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 406084ns
I1107 02:07:01.653594 32119 replica.cpp:323] Persisted replica status to STARTING
I1107 02:07:01.653777 32115 recover.cpp:475] Replica is in STARTING status
I1107 02:07:01.653800 32112 master.cpp:458] Using default 'crammd5' authenticator
I1107 02:07:01.653951 32112 master.cpp:495] Authorization enabled
I1107 02:07:01.654157 32116 whitelist_watcher.cpp:79] No whitelist given
I1107 02:07:01.654228 32121 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 02:07:01.654961 32124 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10572)@172.17.11.150:48396
I1107 02:07:01.655967 32111 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 02:07:01.656518 32124 master.cpp:1606] The newly elected leader is master@172.17.11.150:48396 with id c0e821b3-2504-4653-baae-767323044085
I1107 02:07:01.656561 32124 master.cpp:1619] Elected as the leading master!
I1107 02:07:01.656595 32124 master.cpp:1379] Recovering from registrar
I1107 02:07:01.656664 32114 recover.cpp:566] Updating replica status to VOTING
I1107 02:07:01.656798 32112 registrar.cpp:309] Recovering registrar
I1107 02:07:01.657498 32125 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 545504ns
I1107 02:07:01.657531 32125 replica.cpp:323] Persisted replica status to VOTING
I1107 02:07:01.657646 32118 recover.cpp:580] Successfully joined the Paxos group
I1107 02:07:01.657892 32118 recover.cpp:464] Recover process terminated
I1107 02:07:01.658448 32117 log.cpp:661] Attempting to start the writer
I1107 02:07:01.659893 32126 replica.cpp:496] Replica received implicit promise request from (10573)@172.17.11.150:48396 with proposal 1
I1107 02:07:01.660500 32126 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 565948ns
I1107 02:07:01.660531 32126 replica.cpp:345] Persisted promised to 1
I1107 02:07:01.661321 32114 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 02:07:01.662683 32119 replica.cpp:391] Replica received explicit promise request from (10574)@172.17.11.150:48396 for position 0 with proposal 2
I1107 02:07:01.663045 32119 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 274667ns
I1107 02:07:01.663067 32119 replica.cpp:715] Persisted action at 0
I1107 02:07:01.664083 32125 replica.cpp:540] Replica received write request for position 0 from (10575)@172.17.11.150:48396
I1107 02:07:01.664144 32125 leveldb.cpp:438] Reading position from leveldb took 25829ns
I1107 02:07:01.664582 32125 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 393545ns
I1107 02:07:01.664609 32125 replica.cpp:715] Persisted action at 0
I1107 02:07:01.665124 32113 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 02:07:01.665443 32113 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 289698ns
I1107 02:07:01.665467 32113 replica.cpp:715] Persisted action at 0
I1107 02:07:01.665482 32113 replica.cpp:700] Replica learned NOP action at position 0
I1107 02:07:01.665973 32122 log.cpp:677] Writer started with ending position 0
I1107 02:07:01.666833 32113 leveldb.cpp:438] Reading position from leveldb took 21846ns
I1107 02:07:01.667626 32119 registrar.cpp:342] Successfully fetched the registry (0B) in 10.777088ms
I1107 02:07:01.667726 32119 registrar.cpp:441] Applied 1 operations in 22174ns; attempting to update the 'registry'
I1107 02:07:01.668341 32113 log.cpp:685] Attempting to append 178 bytes to the log
I1107 02:07:01.668453 32124 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 02:07:01.669224 32116 replica.cpp:540] Replica received write request for position 1 from (10576)@172.17.11.150:48396
I1107 02:07:01.669780 32116 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 485056ns
I1107 02:07:01.669818 32116 replica.cpp:715] Persisted action at 1
I1107 02:07:01.670647 32126 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:07:01.671074 32126 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 385427ns
I1107 02:07:01.671106 32126 replica.cpp:715] Persisted action at 1
I1107 02:07:01.671129 32126 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:07:01.672353 32125 registrar.cpp:486] Successfully updated the 'registry' in 4.48896ms
I1107 02:07:01.672575 32125 registrar.cpp:372] Successfully recovered registrar
I1107 02:07:01.672674 32111 log.cpp:704] Attempting to truncate the log to 1
I1107 02:07:01.673060 32123 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:07:01.672909 32119 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:07:01.674094 32117 replica.cpp:540] Replica received write request for position 2 from (10577)@172.17.11.150:48396
I1107 02:07:01.674520 32117 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 372632ns
I1107 02:07:01.674554 32117 replica.cpp:715] Persisted action at 2
I1107 02:07:01.675365 32124 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:07:01.675876 32124 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 467224ns
I1107 02:07:01.675976 32124 leveldb.cpp:401] Deleting ~1 keys from leveldb took 48168ns
I1107 02:07:01.676022 32124 replica.cpp:715] Persisted action at 2
I1107 02:07:01.676076 32124 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:07:01.685045 32115 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:07:01.685395 32115 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.150:49401
I1107 02:07:01.685868 32115 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:07:01.685950 32115 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:07:01.686702 32115 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:07:01.687374 32122 hierarchical.cpp:185] Added framework c0e821b3-2504-4653-baae-767323044085-0000
I1107 02:07:01.687423 32122 hierarchical.cpp:952] No resources available to allocate!
I1107 02:07:01.687458 32122 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:07:01.687484 32122 hierarchical.cpp:851] Performed allocation for 0 slaves in 84322ns
I1107 02:07:01.687615 32114 master.hpp:1439] Sending heartbeat to c0e821b3-2504-4653-baae-767323044085-0000
I1107 02:07:01.687885 32092 master.cpp:922] Master terminating
I1107 02:07:01.688063 32114 hierarchical.cpp:220] Removed framework c0e821b3-2504-4653-baae-767323044085-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/1 (45 ms)
[----------] 20 tests from ContentType/SchedulerHttpApiTest (1008 ms total)
[----------] Global test environment tear-down
[==========] 831 tests from 113 test cases ran. (331972 ms total)
[ PASSED ] 830 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] RegistryClientTest.BadRequest
1 FAILED TEST
YOU HAVE 8 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-1446860227-21020
Untagged: mesos-1446860227-21020:latest
Deleted: 3c43dea30b266089bd997cbca1a0ed7ff104034945bb3cad0758f3f36543e371
Deleted: 4ac4ac1c0eb7995ff2895beca86871d34ce5bdca019f32b5c39ff04da30439c9
Deleted: 3a755b1bcf333e7bd1e71687748e549738da7aa5bf960b2c02ce84a55205aa74
Deleted: 6b4f0c2880f6c3fba3fad756bf1d20b518e7c0ecb08d0a3ae6b882f567e46503
Deleted: 43a63fe7bc1560c56d8fe29898cd55358c6c7bf9210e8eec27bbe7ef8924178c
Deleted: bd0ecc3540695844379f6fadd1183f0fffc9d2476cee44e27bd9c99cf8e8a818
Deleted: caca14b086d84b34a34d13c28775277e03b135fc9e9942cbf4381ae2bfed7d9e
Deleted: 728e4a0e33c5975f6ae6f33dc0221610016a9761028c396ae8eaf6b7249be08a
Deleted: d3472fd34d7b5394fa886ea7ae18c0d2a33294a42c3e081f3e16578693f76d4a
Deleted: 169dbde98d8f9a5680ff5561bae3451fa812ac0b8db90be94f0bc173408a1b99
Deleted: e161548502939771656b9ecdde21738233de238e011afbb2b090100f1e2b31be
Deleted: c3cefbb2e765e3b0265b830b7d24fea2a91e7e9220afe16aa00291c6cd2aa9b9
Build step 'Execute shell' marked build as failure