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:29:34 UTC
Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,ubuntu:14.04,docker||Hadoop #1188
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/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 138981 lines...]
I1107 01:24:42.854826 29456 replica.cpp:540] Replica received write request for position 1 from (10564)@172.17.11.125:36286
I1107 01:24:42.855305 29456 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 447599ns
I1107 01:24:42.855339 29456 replica.cpp:715] Persisted action at 1
I1107 01:24:42.856086 29461 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:24:42.856504 29461 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 390711ns
I1107 01:24:42.856541 29461 replica.cpp:715] Persisted action at 1
I1107 01:24:42.856575 29461 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:24:42.857615 29455 registrar.cpp:486] Successfully updated the 'registry' in 4.427008ms
I1107 01:24:42.857772 29455 registrar.cpp:372] Successfully recovered registrar
I1107 01:24:42.857940 29465 log.cpp:704] Attempting to truncate the log to 1
I1107 01:24:42.858119 29451 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:24:42.858160 29457 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:24:42.859027 29453 replica.cpp:540] Replica received write request for position 2 from (10565)@172.17.11.125:36286
I1107 01:24:42.859449 29453 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 394691ns
I1107 01:24:42.859482 29453 replica.cpp:715] Persisted action at 2
I1107 01:24:42.860187 29457 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:24:42.860705 29457 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 422230ns
I1107 01:24:42.860769 29457 leveldb.cpp:401] Deleting ~1 keys from leveldb took 44401ns
I1107 01:24:42.860795 29457 replica.cpp:715] Persisted action at 2
I1107 01:24:42.860817 29457 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:24:42.870443 29465 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:24:42.870862 29465 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.125:51515
I1107 01:24:42.871212 29465 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:24:42.871247 29465 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:24:42.871947 29465 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:24:42.872324 29462 hierarchical.cpp:185] Added framework f9ae5738-4450-4920-81d0-550e44d5650f-0000
I1107 01:24:42.872375 29462 hierarchical.cpp:952] No resources available to allocate!
I1107 01:24:42.872403 29462 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:24:42.872431 29462 hierarchical.cpp:851] Performed allocation for 0 slaves in 90033ns
I1107 01:24:42.872551 29458 master.hpp:1439] Sending heartbeat to f9ae5738-4450-4920-81d0-550e44d5650f-0000
I1107 01:24:42.873276 29452 master.cpp:922] Master terminating
I1107 01:24:42.873492 29464 hierarchical.cpp:220] Removed framework f9ae5738-4450-4920-81d0-550e44d5650f-0000
[ OK ] ContentType/SchedulerHttpApiTest.NoAcceptHeader/1 (50 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/0
I1107 01:24:42.883016 29432 leveldb.cpp:176] Opened db in 3.059821ms
I1107 01:24:42.884071 29432 leveldb.cpp:183] Compacted db in 1.032447ms
I1107 01:24:42.884130 29432 leveldb.cpp:198] Created db iterator in 28559ns
I1107 01:24:42.884151 29432 leveldb.cpp:204] Seeked to beginning of db in 7730ns
I1107 01:24:42.884162 29432 leveldb.cpp:273] Iterated through 0 keys in the db in 6154ns
I1107 01:24:42.884196 29432 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 01:24:42.884687 29464 recover.cpp:449] Starting replica recovery
I1107 01:24:42.884912 29464 recover.cpp:475] Replica is in EMPTY status
I1107 01:24:42.885896 29452 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10569)@172.17.11.125:36286
I1107 01:24:42.886466 29457 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 01:24:42.887882 29458 recover.cpp:566] Updating replica status to STARTING
I1107 01:24:42.888659 29450 master.cpp:367] Master c4ab4a73-41f5-4961-9220-d63be3301159 (6d8f7362144a) started on 172.17.11.125:36286
I1107 01:24:42.888746 29462 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 722555ns
I1107 01:24:42.888782 29462 replica.cpp:323] Persisted replica status to STARTING
I1107 01:24:42.889020 29460 recover.cpp:475] Replica is in STARTING status
I1107 01:24:42.888742 29450 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/GgekcY/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/GgekcY/master" --zk_session_timeout="10secs"
I1107 01:24:42.889104 29450 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 01:24:42.889118 29450 master.cpp:419] Master only allowing authenticated slaves to register
I1107 01:24:42.889127 29450 credentials.hpp:37] Loading credentials for authentication from '/tmp/GgekcY/credentials'
I1107 01:24:42.889408 29450 master.cpp:458] Using default 'crammd5' authenticator
I1107 01:24:42.889538 29450 master.cpp:495] Authorization enabled
I1107 01:24:42.889734 29460 whitelist_watcher.cpp:79] No whitelist given
I1107 01:24:42.889793 29452 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 01:24:42.890154 29452 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10570)@172.17.11.125:36286
I1107 01:24:42.891580 29451 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 01:24:42.891875 29455 master.cpp:1606] The newly elected leader is master@172.17.11.125:36286 with id c4ab4a73-41f5-4961-9220-d63be3301159
I1107 01:24:42.891917 29455 master.cpp:1619] Elected as the leading master!
I1107 01:24:42.891932 29455 master.cpp:1379] Recovering from registrar
I1107 01:24:42.892182 29454 registrar.cpp:309] Recovering registrar
I1107 01:24:42.892451 29452 recover.cpp:566] Updating replica status to VOTING
I1107 01:24:42.893152 29464 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 457449ns
I1107 01:24:42.893184 29464 replica.cpp:323] Persisted replica status to VOTING
I1107 01:24:42.893331 29458 recover.cpp:580] Successfully joined the Paxos group
I1107 01:24:42.893590 29458 recover.cpp:464] Recover process terminated
I1107 01:24:42.894021 29453 log.cpp:661] Attempting to start the writer
I1107 01:24:42.895321 29452 replica.cpp:496] Replica received implicit promise request from (10571)@172.17.11.125:36286 with proposal 1
I1107 01:24:42.895813 29452 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 472249ns
I1107 01:24:42.895844 29452 replica.cpp:345] Persisted promised to 1
I1107 01:24:42.896502 29465 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 01:24:42.897724 29465 replica.cpp:391] Replica received explicit promise request from (10572)@172.17.11.125:36286 for position 0 with proposal 2
I1107 01:24:42.898267 29465 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 511157ns
I1107 01:24:42.898299 29465 replica.cpp:715] Persisted action at 0
I1107 01:24:42.899389 29462 replica.cpp:540] Replica received write request for position 0 from (10573)@172.17.11.125:36286
I1107 01:24:42.899462 29462 leveldb.cpp:438] Reading position from leveldb took 44336ns
I1107 01:24:42.899946 29462 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 453174ns
I1107 01:24:42.899977 29462 replica.cpp:715] Persisted action at 0
I1107 01:24:42.900668 29463 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 01:24:42.901211 29463 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 461496ns
I1107 01:24:42.901242 29463 replica.cpp:715] Persisted action at 0
I1107 01:24:42.901279 29463 replica.cpp:700] Replica learned NOP action at position 0
I1107 01:24:42.902088 29451 log.cpp:677] Writer started with ending position 0
I1107 01:24:42.903236 29460 leveldb.cpp:438] Reading position from leveldb took 47088ns
I1107 01:24:42.904243 29461 registrar.cpp:342] Successfully fetched the registry (0B) in 12.000768ms
I1107 01:24:42.904439 29461 registrar.cpp:441] Applied 1 operations in 39317ns; attempting to update the 'registry'
I1107 01:24:42.905125 29458 log.cpp:685] Attempting to append 178 bytes to the log
I1107 01:24:42.905282 29459 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 01:24:42.906141 29459 replica.cpp:540] Replica received write request for position 1 from (10574)@172.17.11.125:36286
I1107 01:24:42.906672 29459 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 501072ns
I1107 01:24:42.906710 29459 replica.cpp:715] Persisted action at 1
I1107 01:24:42.907441 29453 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:24:42.907887 29453 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 422857ns
I1107 01:24:42.907923 29453 replica.cpp:715] Persisted action at 1
I1107 01:24:42.907958 29453 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:24:42.909194 29453 registrar.cpp:486] Successfully updated the 'registry' in 4.681984ms
I1107 01:24:42.909375 29453 registrar.cpp:372] Successfully recovered registrar
I1107 01:24:42.909448 29456 log.cpp:704] Attempting to truncate the log to 1
I1107 01:24:42.909595 29465 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:24:42.909899 29461 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:24:42.910658 29454 replica.cpp:540] Replica received write request for position 2 from (10575)@172.17.11.125:36286
I1107 01:24:42.911029 29454 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 342398ns
I1107 01:24:42.911061 29454 replica.cpp:715] Persisted action at 2
I1107 01:24:42.911764 29462 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:24:42.912430 29462 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 643687ns
I1107 01:24:42.912508 29462 leveldb.cpp:401] Deleting ~1 keys from leveldb took 56228ns
I1107 01:24:42.912531 29462 replica.cpp:715] Persisted action at 2
I1107 01:24:42.912554 29462 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:24:42.922128 29456 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:24:42.922502 29456 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.125:51516
I1107 01:24:42.922724 29456 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:24:42.922755 29456 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:24:42.923091 29456 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:24:42.923499 29464 hierarchical.cpp:185] Added framework c4ab4a73-41f5-4961-9220-d63be3301159-0000
I1107 01:24:42.923552 29464 hierarchical.cpp:952] No resources available to allocate!
I1107 01:24:42.923586 29464 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:24:42.923612 29464 hierarchical.cpp:851] Performed allocation for 0 slaves in 96316ns
I1107 01:24:42.923650 29464 master.hpp:1439] Sending heartbeat to c4ab4a73-41f5-4961-9220-d63be3301159-0000
I1107 01:24:42.924751 29460 master.cpp:922] Master terminating
I1107 01:24:42.924943 29464 hierarchical.cpp:220] Removed framework c4ab4a73-41f5-4961-9220-d63be3301159-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/0 (51 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/1
I1107 01:24:42.933894 29432 leveldb.cpp:176] Opened db in 2.999908ms
I1107 01:24:42.934852 29432 leveldb.cpp:183] Compacted db in 935115ns
I1107 01:24:42.934900 29432 leveldb.cpp:198] Created db iterator in 24789ns
I1107 01:24:42.934916 29432 leveldb.cpp:204] Seeked to beginning of db in 7550ns
I1107 01:24:42.934927 29432 leveldb.cpp:273] Iterated through 0 keys in the db in 6050ns
I1107 01:24:42.934959 29432 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 01:24:42.935426 29463 recover.cpp:449] Starting replica recovery
I1107 01:24:42.935750 29463 recover.cpp:475] Replica is in EMPTY status
I1107 01:24:42.936923 29465 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10579)@172.17.11.125:36286
I1107 01:24:42.937475 29450 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 01:24:42.937975 29453 recover.cpp:566] Updating replica status to STARTING
I1107 01:24:42.938894 29457 master.cpp:367] Master 72c007c0-ed15-4bd4-a267-26900fbde5bd (6d8f7362144a) started on 172.17.11.125:36286
I1107 01:24:42.938938 29452 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 712876ns
I1107 01:24:42.938974 29452 replica.cpp:323] Persisted replica status to STARTING
I1107 01:24:42.939220 29463 recover.cpp:475] Replica is in STARTING status
I1107 01:24:42.938942 29457 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/eHAIVs/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/eHAIVs/master" --zk_session_timeout="10secs"
I1107 01:24:42.939409 29457 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 01:24:42.939434 29457 master.cpp:419] Master only allowing authenticated slaves to register
I1107 01:24:42.939451 29457 credentials.hpp:37] Loading credentials for authentication from '/tmp/eHAIVs/credentials'
I1107 01:24:42.939766 29457 master.cpp:458] Using default 'crammd5' authenticator
I1107 01:24:42.939900 29457 master.cpp:495] Authorization enabled
I1107 01:24:42.940114 29454 whitelist_watcher.cpp:79] No whitelist given
I1107 01:24:42.940248 29464 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 01:24:42.940346 29459 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10580)@172.17.11.125:36286
I1107 01:24:42.940862 29451 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 01:24:42.941467 29460 recover.cpp:566] Updating replica status to VOTING
I1107 01:24:42.941994 29451 master.cpp:1606] The newly elected leader is master@172.17.11.125:36286 with id 72c007c0-ed15-4bd4-a267-26900fbde5bd
I1107 01:24:42.942044 29451 master.cpp:1619] Elected as the leading master!
I1107 01:24:42.942080 29451 master.cpp:1379] Recovering from registrar
I1107 01:24:42.942045 29452 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 446440ns
I1107 01:24:42.942286 29450 registrar.cpp:309] Recovering registrar
I1107 01:24:42.942303 29452 replica.cpp:323] Persisted replica status to VOTING
I1107 01:24:42.942797 29465 recover.cpp:580] Successfully joined the Paxos group
I1107 01:24:42.943150 29465 recover.cpp:464] Recover process terminated
I1107 01:24:42.943629 29455 log.cpp:661] Attempting to start the writer
I1107 01:24:42.945049 29457 replica.cpp:496] Replica received implicit promise request from (10581)@172.17.11.125:36286 with proposal 1
I1107 01:24:42.945564 29457 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 488983ns
I1107 01:24:42.945595 29457 replica.cpp:345] Persisted promised to 1
I1107 01:24:42.946302 29463 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 01:24:42.947682 29458 replica.cpp:391] Replica received explicit promise request from (10582)@172.17.11.125:36286 for position 0 with proposal 2
I1107 01:24:42.948125 29458 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 414856ns
I1107 01:24:42.948156 29458 replica.cpp:715] Persisted action at 0
I1107 01:24:42.949394 29463 replica.cpp:540] Replica received write request for position 0 from (10583)@172.17.11.125:36286
I1107 01:24:42.949476 29463 leveldb.cpp:438] Reading position from leveldb took 53511ns
I1107 01:24:42.949935 29463 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 415120ns
I1107 01:24:42.949970 29463 replica.cpp:715] Persisted action at 0
I1107 01:24:42.950824 29460 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 01:24:42.951290 29460 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 441409ns
I1107 01:24:42.951321 29460 replica.cpp:715] Persisted action at 0
I1107 01:24:42.951342 29460 replica.cpp:700] Replica learned NOP action at position 0
I1107 01:24:42.952044 29450 log.cpp:677] Writer started with ending position 0
I1107 01:24:42.953065 29453 leveldb.cpp:438] Reading position from leveldb took 58098ns
I1107 01:24:42.953968 29464 registrar.cpp:342] Successfully fetched the registry (0B) in 11.63392ms
I1107 01:24:42.954102 29464 registrar.cpp:441] Applied 1 operations in 41999ns; attempting to update the 'registry'
I1107 01:24:42.954766 29458 log.cpp:685] Attempting to append 178 bytes to the log
I1107 01:24:42.955004 29461 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 01:24:42.955788 29463 replica.cpp:540] Replica received write request for position 1 from (10584)@172.17.11.125:36286
I1107 01:24:42.956334 29463 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 519357ns
I1107 01:24:42.956367 29463 replica.cpp:715] Persisted action at 1
I1107 01:24:42.956974 29458 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 01:24:42.957432 29458 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 438908ns
I1107 01:24:42.957461 29458 replica.cpp:715] Persisted action at 1
I1107 01:24:42.957483 29458 replica.cpp:700] Replica learned APPEND action at position 1
I1107 01:24:42.958501 29461 registrar.cpp:486] Successfully updated the 'registry' in 4.336128ms
I1107 01:24:42.958669 29461 registrar.cpp:372] Successfully recovered registrar
I1107 01:24:42.958715 29452 log.cpp:704] Attempting to truncate the log to 1
I1107 01:24:42.958827 29463 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 01:24:42.959153 29460 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 01:24:42.959776 29450 replica.cpp:540] Replica received write request for position 2 from (10585)@172.17.11.125:36286
I1107 01:24:42.960212 29450 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 407006ns
I1107 01:24:42.960243 29450 replica.cpp:715] Persisted action at 2
I1107 01:24:42.960893 29454 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 01:24:42.961364 29454 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 451162ns
I1107 01:24:42.961432 29454 leveldb.cpp:401] Deleting ~1 keys from leveldb took 49928ns
I1107 01:24:42.961457 29454 replica.cpp:715] Persisted action at 2
I1107 01:24:42.961480 29454 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 01:24:42.971534 29458 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 01:24:42.971792 29458 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.11.125:51517
I1107 01:24:42.972194 29458 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 01:24:42.972223 29458 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 01:24:42.972586 29457 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 01:24:42.972992 29463 hierarchical.cpp:185] Added framework 72c007c0-ed15-4bd4-a267-26900fbde5bd-0000
I1107 01:24:42.973047 29463 hierarchical.cpp:952] No resources available to allocate!
I1107 01:24:42.973083 29463 hierarchical.cpp:1045] No inverse offers to send out!
I1107 01:24:42.973109 29463 hierarchical.cpp:851] Performed allocation for 0 slaves in 100379ns
I1107 01:24:42.973323 29453 master.hpp:1439] Sending heartbeat to 72c007c0-ed15-4bd4-a267-26900fbde5bd-0000
I1107 01:24:42.973986 29465 master.cpp:922] Master terminating
I1107 01:24:42.974170 29454 hierarchical.cpp:220] Removed framework 72c007c0-ed15-4bd4-a267-26900fbde5bd-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/1 (49 ms)
[----------] 20 tests from ContentType/SchedulerHttpApiTest (1059 ms total)
[----------] Global test environment tear-down
[==========] 830 tests from 113 test cases ran. (318115 ms total)
[ PASSED ] 829 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-1446857857-13474
Untagged: mesos-1446857857-13474:latest
Deleted: 7d0c58b06b6ae27e236611cb9e6f9c4f7aec25f04009ea1cbe29e975a119fdc8
Deleted: 514ccc2f4aed4af5e30dccc8e2c40f849016616816dcccea2b59291a74c69679
Deleted: dedacaf2031b2b78259ee50796fd69c1a8f5f7abebc1b2896ce816f84ecc8a66
Deleted: 3a1b8be1572023aee792be8aa0fe29c0862983b5eeeab57f019e29eeb7cf3da2
Deleted: 1e8e89d96d0801ee24c0b520f82d3ad69fc9d1a19094f8d8904d3e2e21d989ae
Deleted: 554ed2962c14e152bad3ce50e9bc57d62e946298545ae4269b74d911e6accbde
Deleted: 8a1c4b50674595a15e053836db7cb497b4c804540edcdeb76d612024f63c89fd
Deleted: 006328677e993dfa5a3884b0d3cd2d7507581cc433f93202e8fb6877a771a7a3
Deleted: a0dcd8e06b201019a8d8896b6b2bf7bb970225a2b9f5d44402b9c0982d7ca756
Deleted: 28fe61fde484233a3361c96e289b8a9ea4ff2019681687ffce4dbdb9cc546680
Deleted: b52931c07e06862207d7f6b57256d30c4818aed1068b42b939922e497694d3c3
Deleted: b1425ab9b4ab8f8b0043f7bd413534a1ff8e2ffa92f75fe2779c414dcc73949d
Deleted: ff4f32170ced299c8cca7634b60e3da2ecf3058238c9a23fa5251f8e570f64d8
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose --enable-libevent --enable-ssl,ubuntu:14.04,docker||Hadoop #1190
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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/1190/changes>
Build failed in Jenkins: Mesos » clang,--verbose --enable-libevent --enable-ssl,ubuntu:14.04,docker||Hadoop #1189
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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/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 140327 lines...]
I1107 02:23:11.474130 29462 replica.cpp:540] Replica received write request for position 1 from (10574)@172.17.15.250:47332
I1107 02:23:11.494917 29462 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 20.766809ms
I1107 02:23:11.494958 29462 replica.cpp:715] Persisted action at 1
I1107 02:23:11.495748 29466 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:23:11.520153 29466 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 24.385033ms
I1107 02:23:11.520201 29466 replica.cpp:715] Persisted action at 1
I1107 02:23:11.520226 29466 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:23:11.521394 29453 registrar.cpp:486] Successfully updated the 'registry' in 48.538112ms
I1107 02:23:11.521548 29453 registrar.cpp:372] Successfully recovered registrar
I1107 02:23:11.521734 29458 log.cpp:704] Attempting to truncate the log to 1
I1107 02:23:11.522063 29454 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:23:11.522130 29456 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:23:11.522924 29458 replica.cpp:540] Replica received write request for position 2 from (10575)@172.17.15.250:47332
I1107 02:23:11.553697 29458 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 30.75264ms
I1107 02:23:11.553742 29458 replica.cpp:715] Persisted action at 2
I1107 02:23:11.554514 29461 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:23:11.602707 29461 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 48.182712ms
I1107 02:23:11.602844 29461 leveldb.cpp:401] Deleting ~1 keys from leveldb took 81272ns
I1107 02:23:11.602880 29461 replica.cpp:715] Persisted action at 2
I1107 02:23:11.602921 29461 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:23:11.615744 29464 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:23:11.616063 29464 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.250:50854
I1107 02:23:11.616530 29464 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:23:11.616571 29464 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:23:11.617005 29464 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:23:11.617529 29468 hierarchical.cpp:185] Added framework 72c79d80-ca36-49b4-8007-b495b4ea9123-0000
I1107 02:23:11.617606 29468 hierarchical.cpp:952] No resources available to allocate!
I1107 02:23:11.617655 29457 master.hpp:1439] Sending heartbeat to 72c79d80-ca36-49b4-8007-b495b4ea9123-0000
I1107 02:23:11.617660 29468 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:23:11.617732 29468 hierarchical.cpp:851] Performed allocation for 0 slaves in 188027ns
I1107 02:23:11.618948 29463 master.cpp:922] Master terminating
I1107 02:23:11.619175 29464 hierarchical.cpp:220] Removed framework 72c79d80-ca36-49b4-8007-b495b4ea9123-0000
[ OK ] ContentType/SchedulerHttpApiTest.NoAcceptHeader/1 (572 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/0
I1107 02:23:11.737593 29435 leveldb.cpp:176] Opened db in 111.964425ms
I1107 02:23:11.779646 29435 leveldb.cpp:183] Compacted db in 42.026829ms
I1107 02:23:11.779713 29435 leveldb.cpp:198] Created db iterator in 34211ns
I1107 02:23:11.779736 29435 leveldb.cpp:204] Seeked to beginning of db in 8347ns
I1107 02:23:11.779747 29435 leveldb.cpp:273] Iterated through 0 keys in the db in 6297ns
I1107 02:23:11.779791 29435 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 02:23:11.780284 29468 recover.cpp:449] Starting replica recovery
I1107 02:23:11.780743 29454 recover.cpp:475] Replica is in EMPTY status
I1107 02:23:11.781975 29458 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10579)@172.17.15.250:47332
I1107 02:23:11.782423 29456 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 02:23:11.783004 29453 recover.cpp:566] Updating replica status to STARTING
I1107 02:23:11.783751 29458 master.cpp:367] Master 2ae904bf-9eea-4120-a18a-2b8cd59c2fbe (8ef282006487) started on 172.17.15.250:47332
I1107 02:23:11.783789 29458 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/57MAbE/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/57MAbE/master" --zk_session_timeout="10secs"
I1107 02:23:11.784135 29458 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 02:23:11.784149 29458 master.cpp:419] Master only allowing authenticated slaves to register
I1107 02:23:11.784158 29458 credentials.hpp:37] Loading credentials for authentication from '/tmp/57MAbE/credentials'
I1107 02:23:11.784430 29458 master.cpp:458] Using default 'crammd5' authenticator
I1107 02:23:11.784567 29458 master.cpp:495] Authorization enabled
I1107 02:23:11.784909 29466 whitelist_watcher.cpp:79] No whitelist given
I1107 02:23:11.784981 29459 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 02:23:11.786573 29453 master.cpp:1606] The newly elected leader is master@172.17.15.250:47332 with id 2ae904bf-9eea-4120-a18a-2b8cd59c2fbe
I1107 02:23:11.786617 29453 master.cpp:1619] Elected as the leading master!
I1107 02:23:11.786631 29453 master.cpp:1379] Recovering from registrar
I1107 02:23:11.786782 29456 registrar.cpp:309] Recovering registrar
I1107 02:23:11.812273 29467 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 29.114047ms
I1107 02:23:11.812319 29467 replica.cpp:323] Persisted replica status to STARTING
I1107 02:23:11.812580 29467 recover.cpp:475] Replica is in STARTING status
I1107 02:23:11.813628 29454 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10580)@172.17.15.250:47332
I1107 02:23:11.813900 29464 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 02:23:11.814504 29453 recover.cpp:566] Updating replica status to VOTING
I1107 02:23:11.845779 29468 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 31.161127ms
I1107 02:23:11.845826 29468 replica.cpp:323] Persisted replica status to VOTING
I1107 02:23:11.846030 29468 recover.cpp:580] Successfully joined the Paxos group
I1107 02:23:11.846246 29468 recover.cpp:464] Recover process terminated
I1107 02:23:11.846812 29468 log.cpp:661] Attempting to start the writer
I1107 02:23:11.848260 29453 replica.cpp:496] Replica received implicit promise request from (10581)@172.17.15.250:47332 with proposal 1
I1107 02:23:11.879168 29453 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 30.886218ms
I1107 02:23:11.879214 29453 replica.cpp:345] Persisted promised to 1
I1107 02:23:11.880146 29464 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 02:23:11.881523 29462 replica.cpp:391] Replica received explicit promise request from (10582)@172.17.15.250:47332 for position 0 with proposal 2
I1107 02:23:11.912705 29462 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 31.150128ms
I1107 02:23:11.912750 29462 replica.cpp:715] Persisted action at 0
I1107 02:23:11.914075 29468 replica.cpp:540] Replica received write request for position 0 from (10583)@172.17.15.250:47332
I1107 02:23:11.914149 29468 leveldb.cpp:438] Reading position from leveldb took 47248ns
I1107 02:23:11.946149 29468 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 31.969483ms
I1107 02:23:11.946197 29468 replica.cpp:715] Persisted action at 0
I1107 02:23:11.946959 29460 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 02:23:11.979542 29460 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 32.525951ms
I1107 02:23:11.979586 29460 replica.cpp:715] Persisted action at 0
I1107 02:23:11.979609 29460 replica.cpp:700] Replica learned NOP action at position 0
I1107 02:23:11.980351 29467 log.cpp:677] Writer started with ending position 0
I1107 02:23:11.981626 29460 leveldb.cpp:438] Reading position from leveldb took 54372ns
I1107 02:23:11.982682 29454 registrar.cpp:342] Successfully fetched the registry (0B) in 195.855872ms
I1107 02:23:11.982839 29454 registrar.cpp:441] Applied 1 operations in 53250ns; attempting to update the 'registry'
I1107 02:23:11.983620 29455 log.cpp:685] Attempting to append 178 bytes to the log
I1107 02:23:11.983803 29460 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 02:23:11.984509 29465 replica.cpp:540] Replica received write request for position 1 from (10584)@172.17.15.250:47332
I1107 02:23:12.021412 29465 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 36.870817ms
I1107 02:23:12.021459 29465 replica.cpp:715] Persisted action at 1
I1107 02:23:12.022155 29458 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:23:12.055018 29458 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 32.843593ms
I1107 02:23:12.055063 29458 replica.cpp:715] Persisted action at 1
I1107 02:23:12.055086 29458 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:23:12.056248 29461 registrar.cpp:486] Successfully updated the 'registry' in 73.324032ms
I1107 02:23:12.056466 29461 registrar.cpp:372] Successfully recovered registrar
I1107 02:23:12.056538 29453 log.cpp:704] Attempting to truncate the log to 1
I1107 02:23:12.056983 29464 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:23:12.056931 29467 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:23:12.057777 29461 replica.cpp:540] Replica received write request for position 2 from (10585)@172.17.15.250:47332
I1107 02:23:12.088335 29461 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 30.527814ms
I1107 02:23:12.088381 29461 replica.cpp:715] Persisted action at 2
I1107 02:23:12.089293 29455 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:23:12.121801 29455 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 32.483428ms
I1107 02:23:12.121881 29455 leveldb.cpp:401] Deleting ~1 keys from leveldb took 53643ns
I1107 02:23:12.121904 29455 replica.cpp:715] Persisted action at 2
I1107 02:23:12.121927 29455 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:23:12.129734 29456 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:23:12.130128 29456 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.250:50961
I1107 02:23:12.130373 29456 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:23:12.130411 29456 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:23:12.130741 29456 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:23:12.131181 29457 hierarchical.cpp:185] Added framework 2ae904bf-9eea-4120-a18a-2b8cd59c2fbe-0000
I1107 02:23:12.131248 29457 hierarchical.cpp:952] No resources available to allocate!
I1107 02:23:12.131402 29457 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:23:12.131338 29463 master.hpp:1439] Sending heartbeat to 2ae904bf-9eea-4120-a18a-2b8cd59c2fbe-0000
I1107 02:23:12.131573 29457 hierarchical.cpp:851] Performed allocation for 0 slaves in 376844ns
I1107 02:23:12.132583 29456 master.cpp:922] Master terminating
I1107 02:23:12.132791 29466 hierarchical.cpp:220] Removed framework 2ae904bf-9eea-4120-a18a-2b8cd59c2fbe-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/0 (514 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.DefaultAccept/1
I1107 02:23:12.292557 29435 leveldb.cpp:176] Opened db in 153.314548ms
I1107 02:23:12.339184 29435 leveldb.cpp:183] Compacted db in 46.598413ms
I1107 02:23:12.339251 29435 leveldb.cpp:198] Created db iterator in 34191ns
I1107 02:23:12.339273 29435 leveldb.cpp:204] Seeked to beginning of db in 11059ns
I1107 02:23:12.339293 29435 leveldb.cpp:273] Iterated through 0 keys in the db in 11012ns
I1107 02:23:12.339354 29435 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1107 02:23:12.340356 29465 recover.cpp:449] Starting replica recovery
I1107 02:23:12.340736 29465 recover.cpp:475] Replica is in EMPTY status
I1107 02:23:12.342236 29462 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10589)@172.17.15.250:47332
I1107 02:23:12.342633 29466 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1107 02:23:12.343387 29458 recover.cpp:566] Updating replica status to STARTING
I1107 02:23:12.343761 29453 master.cpp:367] Master bd7cb57d-b886-4780-a5b4-d373cf0a514e (8ef282006487) started on 172.17.15.250:47332
I1107 02:23:12.343813 29453 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/eorCah/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/eorCah/master" --zk_session_timeout="10secs"
I1107 02:23:12.344292 29453 master.cpp:416] Master allowing unauthenticated frameworks to register
I1107 02:23:12.344306 29453 master.cpp:419] Master only allowing authenticated slaves to register
I1107 02:23:12.344316 29453 credentials.hpp:37] Loading credentials for authentication from '/tmp/eorCah/credentials'
I1107 02:23:12.344645 29453 master.cpp:458] Using default 'crammd5' authenticator
I1107 02:23:12.344787 29453 master.cpp:495] Authorization enabled
I1107 02:23:12.345065 29458 whitelist_watcher.cpp:79] No whitelist given
I1107 02:23:12.345123 29463 hierarchical.cpp:140] Initialized hierarchical allocator process
I1107 02:23:12.346719 29454 master.cpp:1606] The newly elected leader is master@172.17.15.250:47332 with id bd7cb57d-b886-4780-a5b4-d373cf0a514e
I1107 02:23:12.346756 29454 master.cpp:1619] Elected as the leading master!
I1107 02:23:12.346772 29454 master.cpp:1379] Recovering from registrar
I1107 02:23:12.346881 29458 registrar.cpp:309] Recovering registrar
I1107 02:23:12.385273 29460 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 41.748794ms
I1107 02:23:12.385318 29460 replica.cpp:323] Persisted replica status to STARTING
I1107 02:23:12.385566 29460 recover.cpp:475] Replica is in STARTING status
I1107 02:23:12.386651 29459 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10590)@172.17.15.250:47332
I1107 02:23:12.387028 29462 recover.cpp:195] Received a recover response from a replica in STARTING status
I1107 02:23:12.387424 29456 recover.cpp:566] Updating replica status to VOTING
I1107 02:23:12.427079 29460 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 39.566939ms
I1107 02:23:12.427124 29460 replica.cpp:323] Persisted replica status to VOTING
I1107 02:23:12.427269 29460 recover.cpp:580] Successfully joined the Paxos group
I1107 02:23:12.427532 29460 recover.cpp:464] Recover process terminated
I1107 02:23:12.428031 29455 log.cpp:661] Attempting to start the writer
I1107 02:23:12.429154 29460 replica.cpp:496] Replica received implicit promise request from (10591)@172.17.15.250:47332 with proposal 1
I1107 02:23:12.468896 29460 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 39.720681ms
I1107 02:23:12.468940 29460 replica.cpp:345] Persisted promised to 1
I1107 02:23:12.469933 29456 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1107 02:23:12.471119 29458 replica.cpp:391] Replica received explicit promise request from (10592)@172.17.15.250:47332 for position 0 with proposal 2
I1107 02:23:12.505583 29458 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 34.429181ms
I1107 02:23:12.505630 29458 replica.cpp:715] Persisted action at 0
I1107 02:23:12.506889 29458 replica.cpp:540] Replica received write request for position 0 from (10593)@172.17.15.250:47332
I1107 02:23:12.506999 29458 leveldb.cpp:438] Reading position from leveldb took 81075ns
I1107 02:23:12.530807 29458 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 23.770144ms
I1107 02:23:12.530853 29458 replica.cpp:715] Persisted action at 0
I1107 02:23:12.531733 29462 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1107 02:23:12.555912 29462 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 24.15471ms
I1107 02:23:12.555958 29462 replica.cpp:715] Persisted action at 0
I1107 02:23:12.555994 29462 replica.cpp:700] Replica learned NOP action at position 0
I1107 02:23:12.556668 29465 log.cpp:677] Writer started with ending position 0
I1107 02:23:12.557987 29458 leveldb.cpp:438] Reading position from leveldb took 81027ns
I1107 02:23:12.559039 29464 registrar.cpp:342] Successfully fetched the registry (0B) in 212.103936ms
I1107 02:23:12.559202 29464 registrar.cpp:441] Applied 1 operations in 61230ns; attempting to update the 'registry'
I1107 02:23:12.560014 29453 log.cpp:685] Attempting to append 178 bytes to the log
I1107 02:23:12.560148 29468 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1107 02:23:12.561038 29460 replica.cpp:540] Replica received write request for position 1 from (10594)@172.17.15.250:47332
I1107 02:23:12.581060 29460 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 19.995167ms
I1107 02:23:12.581106 29460 replica.cpp:715] Persisted action at 1
I1107 02:23:12.581877 29465 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1107 02:23:12.606153 29465 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 24.255456ms
I1107 02:23:12.606201 29465 replica.cpp:715] Persisted action at 1
I1107 02:23:12.606235 29465 replica.cpp:700] Replica learned APPEND action at position 1
I1107 02:23:12.607203 29468 registrar.cpp:486] Successfully updated the 'registry' in 47.929856ms
I1107 02:23:12.607393 29468 registrar.cpp:372] Successfully recovered registrar
I1107 02:23:12.607467 29460 log.cpp:704] Attempting to truncate the log to 1
I1107 02:23:12.607628 29462 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1107 02:23:12.607836 29456 master.cpp:1416] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1107 02:23:12.608541 29466 replica.cpp:540] Replica received write request for position 2 from (10595)@172.17.15.250:47332
I1107 02:23:12.631345 29466 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 22.77779ms
I1107 02:23:12.631392 29466 replica.cpp:715] Persisted action at 2
I1107 02:23:12.632213 29455 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1107 02:23:12.656462 29455 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 24.224698ms
I1107 02:23:12.656565 29455 leveldb.cpp:401] Deleting ~1 keys from leveldb took 77102ns
I1107 02:23:12.656605 29455 replica.cpp:715] Persisted action at 2
I1107 02:23:12.656642 29455 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1107 02:23:12.661067 29455 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1107 02:23:12.661406 29455 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.15.250:51081
I1107 02:23:12.661783 29455 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1107 02:23:12.661810 29455 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1107 02:23:12.662111 29455 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1107 02:23:12.662456 29456 hierarchical.cpp:185] Added framework bd7cb57d-b886-4780-a5b4-d373cf0a514e-0000
I1107 02:23:12.662510 29456 hierarchical.cpp:952] No resources available to allocate!
I1107 02:23:12.662550 29454 master.hpp:1439] Sending heartbeat to bd7cb57d-b886-4780-a5b4-d373cf0a514e-0000
I1107 02:23:12.662649 29456 hierarchical.cpp:1045] No inverse offers to send out!
I1107 02:23:12.662680 29456 hierarchical.cpp:851] Performed allocation for 0 slaves in 210911ns
I1107 02:23:12.663802 29435 master.cpp:922] Master terminating
I1107 02:23:12.664094 29457 hierarchical.cpp:220] Removed framework bd7cb57d-b886-4780-a5b4-d373cf0a514e-0000
[ OK ] ContentType/SchedulerHttpApiTest.DefaultAccept/1 (531 ms)
[----------] 20 tests from ContentType/SchedulerHttpApiTest (11029 ms total)
[----------] Global test environment tear-down
[==========] 830 tests from 113 test cases ran. (689687 ms total)
[ PASSED ] 829 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-1446860710-8276
Untagged: mesos-1446860710-8276:latest
Deleted: 6fa905bb103871b34799f9d8b7a15641ae07cc0a3e785145b61834196730ed2c
Deleted: 6940582ba28db576dee759df00d80b2e3fcf187f59cc00b81876484b4c92a46f
Deleted: df3afa9eb596adc8b2d0295d0afbd9a66cb8b5b32f6a3f0cc779bb94ea58e514
Deleted: a11ebb5ba0f9dffc331f997fd8f89b43ddb13a5bae067f606fca5ce814d7ef5b
Deleted: e3307d86eef53eb774c5e08df718c66259aa01f68777f62ed7991bf17fbe8917
Deleted: 31896135e4df7447c44e91996407cab743c36e1b669302fba8c9ec3affc12b8c
Deleted: 55aa5c674679bcb58a96c632f11fb46b35f533dec85f1186b3bee4db99dd0675
Deleted: c047b1c04c9c5a040422d9b963fa0c04ebb28887164e39c7f00b15f110390b9d
Deleted: 5daa26553f7d5b2aeda64ff76ffb98e163318a73500a96607050d7c5b0159447
Deleted: 893513650dadf3ea2089922817e5b5d38f8a767bb3b5861994821113dc034560
Deleted: 1687092f4d95aff69d2b665af614d12fa263d8ce515d2942674c7165db1daec2
Deleted: 44d41903a52daa84a684091049b6adf5a22c8d6fea7ae61a6753b2b7ec9a6e31
Deleted: b2079908a91256100a050ab6d39e7fcbab1db2654c7b9c4aaed9da9ecb389c87
Build step 'Execute shell' marked build as failure