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/09 19:19:08 UTC
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1203
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1203/changes>
Changes:
[joris.van.remoortere] Quota: Moved `QuotaInfo` protobuf into a separate package.
[joris.van.remoortere] Quota: Extended the Allocator interface with quota-related methods.
------------------------------------------
[...truncated 139272 lines...]
I1109 18:19:14.742561 28062 slave.cpp:2034] Shutting down framework 5d896345-a3a1-4016-b596-da05bdc8fe6d-0000
I1109 18:19:14.742640 28062 slave.cpp:3657] Cleaning up executor 'default' of framework 5d896345-a3a1-4016-b596-da05bdc8fe6d-0000 at executor(132)@172.17.6.22:50606
I1109 18:19:14.743026 28062 slave.cpp:3745] Cleaning up framework 5d896345-a3a1-4016-b596-da05bdc8fe6d-0000
I1109 18:19:14.744081 28052 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_EGnk6Q/slaves/5d896345-a3a1-4016-b596-da05bdc8fe6d-S0/frameworks/5d896345-a3a1-4016-b596-da05bdc8fe6d-0000/executors/default/runs/8aede878-f5c4-4693-9843-a81736b4b98f' for gc 6.99999140284741days in the future
I1109 18:19:14.744247 28052 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_EGnk6Q/slaves/5d896345-a3a1-4016-b596-da05bdc8fe6d-S0/frameworks/5d896345-a3a1-4016-b596-da05bdc8fe6d-0000/executors/default' for gc 6.99999140078815days in the future
I1109 18:19:14.744385 28052 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_EGnk6Q/slaves/5d896345-a3a1-4016-b596-da05bdc8fe6d-S0/frameworks/5d896345-a3a1-4016-b596-da05bdc8fe6d-0000' for gc 6.99999139718815days in the future
I1109 18:19:14.744524 28052 status_update_manager.cpp:284] Closing status update streams for framework 5d896345-a3a1-4016-b596-da05bdc8fe6d-0000
I1109 18:19:14.744588 28052 status_update_manager.cpp:530] Cleaning up status update stream for task 6f17632c-bcd2-4c97-896b-67ddf531b797 of framework 5d896345-a3a1-4016-b596-da05bdc8fe6d-0000
[ OK ] ContentType/SchedulerTest.Message/1 (153 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1109 18:19:14.752151 28032 leveldb.cpp:176] Opened db in 2.370688ms
I1109 18:19:14.752902 28032 leveldb.cpp:183] Compacted db in 712418ns
I1109 18:19:14.752975 28032 leveldb.cpp:198] Created db iterator in 38302ns
I1109 18:19:14.753003 28032 leveldb.cpp:204] Seeked to beginning of db in 26947ns
I1109 18:19:14.753041 28032 leveldb.cpp:273] Iterated through 0 keys in the db in 10748ns
I1109 18:19:14.753125 28032 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1109 18:19:14.754115 28061 recover.cpp:449] Starting replica recovery
I1109 18:19:14.755462 28061 recover.cpp:475] Replica is in EMPTY status
I1109 18:19:14.757050 28053 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10523)@172.17.6.22:50606
I1109 18:19:14.757588 28061 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1109 18:19:14.758955 28052 master.cpp:367] Master 1eb1e716-763c-4ec4-aaee-b020a0dd7e28 (ef6874372e57) started on 172.17.6.22:50606
I1109 18:19:14.759229 28052 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/KEKOcV/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/KEKOcV/master" --zk_session_timeout="10secs"
I1109 18:19:14.759519 28052 master.cpp:416] Master allowing unauthenticated frameworks to register
I1109 18:19:14.759539 28052 master.cpp:419] Master only allowing authenticated slaves to register
I1109 18:19:14.759553 28052 credentials.hpp:37] Loading credentials for authentication from '/tmp/KEKOcV/credentials'
I1109 18:19:14.759847 28052 master.cpp:458] Using default 'crammd5' authenticator
I1109 18:19:14.760040 28052 master.cpp:495] Authorization enabled
I1109 18:19:14.761054 28060 hierarchical.cpp:140] Initialized hierarchical allocator process
I1109 18:19:14.761123 28060 whitelist_watcher.cpp:79] No whitelist given
I1109 18:19:14.762558 28052 recover.cpp:566] Updating replica status to STARTING
I1109 18:19:14.762895 28052 master.cpp:1606] The newly elected leader is master@172.17.6.22:50606 with id 1eb1e716-763c-4ec4-aaee-b020a0dd7e28
I1109 18:19:14.762949 28052 master.cpp:1619] Elected as the leading master!
I1109 18:19:14.762972 28052 master.cpp:1379] Recovering from registrar
I1109 18:19:14.763139 28062 registrar.cpp:309] Recovering registrar
I1109 18:19:14.763700 28061 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 860191ns
I1109 18:19:14.763736 28061 replica.cpp:323] Persisted replica status to STARTING
I1109 18:19:14.764055 28052 recover.cpp:475] Replica is in STARTING status
I1109 18:19:14.765213 28060 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10524)@172.17.6.22:50606
I1109 18:19:14.765919 28061 recover.cpp:195] Received a recover response from a replica in STARTING status
I1109 18:19:14.766362 28060 recover.cpp:566] Updating replica status to VOTING
I1109 18:19:14.767001 28058 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 504304ns
I1109 18:19:14.767031 28058 replica.cpp:323] Persisted replica status to VOTING
I1109 18:19:14.767132 28058 recover.cpp:580] Successfully joined the Paxos group
I1109 18:19:14.767580 28058 recover.cpp:464] Recover process terminated
I1109 18:19:14.767912 28051 log.cpp:661] Attempting to start the writer
I1109 18:19:14.770133 28058 replica.cpp:496] Replica received implicit promise request from (10525)@172.17.6.22:50606 with proposal 1
I1109 18:19:14.770651 28058 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 441934ns
I1109 18:19:14.770676 28058 replica.cpp:345] Persisted promised to 1
I1109 18:19:14.771283 28053 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1109 18:19:14.772480 28064 replica.cpp:391] Replica received explicit promise request from (10526)@172.17.6.22:50606 for position 0 with proposal 2
I1109 18:19:14.772845 28064 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 336426ns
I1109 18:19:14.772871 28064 replica.cpp:715] Persisted action at 0
I1109 18:19:14.773900 28056 replica.cpp:540] Replica received write request for position 0 from (10527)@172.17.6.22:50606
I1109 18:19:14.773988 28056 leveldb.cpp:438] Reading position from leveldb took 48797ns
I1109 18:19:14.774371 28056 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 342124ns
I1109 18:19:14.774395 28056 replica.cpp:715] Persisted action at 0
I1109 18:19:14.774919 28051 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1109 18:19:14.775311 28051 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 323228ns
I1109 18:19:14.775339 28051 replica.cpp:715] Persisted action at 0
I1109 18:19:14.775359 28051 replica.cpp:700] Replica learned NOP action at position 0
I1109 18:19:14.775961 28064 log.cpp:677] Writer started with ending position 0
I1109 18:19:14.777091 28050 leveldb.cpp:438] Reading position from leveldb took 47391ns
I1109 18:19:14.778319 28051 registrar.cpp:342] Successfully fetched the registry (0B) in 15136us
I1109 18:19:14.778460 28051 registrar.cpp:441] Applied 1 operations in 55119ns; attempting to update the 'registry'
I1109 18:19:14.779498 28058 log.cpp:685] Attempting to append 174 bytes to the log
I1109 18:19:14.779606 28051 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1109 18:19:14.780355 28058 replica.cpp:540] Replica received write request for position 1 from (10528)@172.17.6.22:50606
I1109 18:19:14.780835 28058 leveldb.cpp:343] Persisting action (193 bytes) to leveldb took 444654ns
I1109 18:19:14.780868 28058 replica.cpp:715] Persisted action at 1
I1109 18:19:14.781461 28062 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1109 18:19:14.781857 28062 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 380506ns
I1109 18:19:14.781885 28062 replica.cpp:715] Persisted action at 1
I1109 18:19:14.781908 28062 replica.cpp:700] Replica learned APPEND action at position 1
I1109 18:19:14.784711 28062 registrar.cpp:486] Successfully updated the 'registry' in 6.180096ms
I1109 18:19:14.784868 28062 registrar.cpp:372] Successfully recovered registrar
I1109 18:19:14.785142 28062 log.cpp:704] Attempting to truncate the log to 1
I1109 18:19:14.785588 28062 master.cpp:1416] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1109 18:19:14.785692 28062 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1109 18:19:14.788064 28059 replica.cpp:540] Replica received write request for position 2 from (10529)@172.17.6.22:50606
I1109 18:19:14.788717 28059 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 620012ns
I1109 18:19:14.788748 28059 replica.cpp:715] Persisted action at 2
I1109 18:19:14.789398 28050 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1109 18:19:14.789791 28050 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 376372ns
I1109 18:19:14.789846 28050 leveldb.cpp:401] Deleting ~1 keys from leveldb took 39574ns
I1109 18:19:14.789865 28050 replica.cpp:715] Persisted action at 2
I1109 18:19:14.789888 28050 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1109 18:19:14.797122 28032 scheduler.cpp:156] Version: 0.26.0
I1109 18:19:14.798223 28050 scheduler.cpp:238] New master detected at master@172.17.6.22:50606
I1109 18:19:14.800354 28060 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.22:50606
I1109 18:19:14.802820 28054 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:19:14.803051 28054 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.22:44412
I1109 18:19:14.803293 28054 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1109 18:19:14.803323 28054 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1109 18:19:14.803618 28054 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1109 18:19:14.804052 28050 hierarchical.cpp:185] Added framework 1eb1e716-763c-4ec4-aaee-b020a0dd7e28-0000
I1109 18:19:14.804088 28064 master.hpp:1439] Sending heartbeat to 1eb1e716-763c-4ec4-aaee-b020a0dd7e28-0000
I1109 18:19:14.804118 28050 hierarchical.cpp:971] No resources available to allocate!
I1109 18:19:14.804162 28050 hierarchical.cpp:1064] No inverse offers to send out!
I1109 18:19:14.804194 28050 hierarchical.cpp:870] Performed allocation for 0 slaves in 130884ns
I1109 18:19:14.805631 28059 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.22:50606
I1109 18:19:14.806162 28059 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.22:50606
I1109 18:19:14.806676 28059 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1109 18:19:14.808442 28053 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.22:50606
I1109 18:19:14.811646 28058 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:19:14.811872 28058 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.22:44413
I1109 18:19:14.812033 28058 master.cpp:2615] Processing REQUEST call for framework 1eb1e716-763c-4ec4-aaee-b020a0dd7e28-0000 (default)
I1109 18:19:14.812623 28058 hierarchical.cpp:449] Received resource request from framework 1eb1e716-763c-4ec4-aaee-b020a0dd7e28-0000
I1109 18:19:14.813132 28061 master.cpp:922] Master terminating
I1109 18:19:14.813730 28058 hierarchical.cpp:220] Removed framework 1eb1e716-763c-4ec4-aaee-b020a0dd7e28-0000
E1109 18:19:14.815656 28060 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (71 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1109 18:19:14.824713 28032 leveldb.cpp:176] Opened db in 2.505052ms
I1109 18:19:14.825492 28032 leveldb.cpp:183] Compacted db in 737628ns
I1109 18:19:14.825539 28032 leveldb.cpp:198] Created db iterator in 29123ns
I1109 18:19:14.825554 28032 leveldb.cpp:204] Seeked to beginning of db in 7463ns
I1109 18:19:14.825564 28032 leveldb.cpp:273] Iterated through 0 keys in the db in 6215ns
I1109 18:19:14.825613 28032 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1109 18:19:14.827430 28063 recover.cpp:449] Starting replica recovery
I1109 18:19:14.827761 28057 recover.cpp:475] Replica is in EMPTY status
I1109 18:19:14.828995 28051 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10534)@172.17.6.22:50606
I1109 18:19:14.829481 28051 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1109 18:19:14.829946 28051 recover.cpp:566] Updating replica status to STARTING
I1109 18:19:14.830966 28051 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 803033ns
I1109 18:19:14.830994 28051 replica.cpp:323] Persisted replica status to STARTING
I1109 18:19:14.831161 28063 recover.cpp:475] Replica is in STARTING status
I1109 18:19:14.831995 28060 master.cpp:367] Master cf0250c9-b3b4-4b28-961d-5fcce836f557 (ef6874372e57) started on 172.17.6.22:50606
I1109 18:19:14.832031 28060 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Ip0GSW/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/Ip0GSW/master" --zk_session_timeout="10secs"
I1109 18:19:14.832401 28060 master.cpp:416] Master allowing unauthenticated frameworks to register
I1109 18:19:14.832422 28060 master.cpp:419] Master only allowing authenticated slaves to register
I1109 18:19:14.832435 28060 credentials.hpp:37] Loading credentials for authentication from '/tmp/Ip0GSW/credentials'
I1109 18:19:14.832777 28060 master.cpp:458] Using default 'crammd5' authenticator
I1109 18:19:14.833196 28065 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10535)@172.17.6.22:50606
I1109 18:19:14.833292 28060 master.cpp:495] Authorization enabled
I1109 18:19:14.833551 28054 recover.cpp:195] Received a recover response from a replica in STARTING status
I1109 18:19:14.833649 28059 hierarchical.cpp:140] Initialized hierarchical allocator process
I1109 18:19:14.833777 28062 whitelist_watcher.cpp:79] No whitelist given
I1109 18:19:14.834245 28054 recover.cpp:566] Updating replica status to VOTING
I1109 18:19:14.835782 28062 master.cpp:1606] The newly elected leader is master@172.17.6.22:50606 with id cf0250c9-b3b4-4b28-961d-5fcce836f557
I1109 18:19:14.835821 28062 master.cpp:1619] Elected as the leading master!
I1109 18:19:14.835835 28062 master.cpp:1379] Recovering from registrar
I1109 18:19:14.835994 28060 registrar.cpp:309] Recovering registrar
I1109 18:19:14.836025 28054 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 1.581105ms
I1109 18:19:14.836066 28054 replica.cpp:323] Persisted replica status to VOTING
I1109 18:19:14.836295 28052 recover.cpp:580] Successfully joined the Paxos group
I1109 18:19:14.836479 28052 recover.cpp:464] Recover process terminated
I1109 18:19:14.838371 28050 log.cpp:661] Attempting to start the writer
I1109 18:19:14.840049 28055 replica.cpp:496] Replica received implicit promise request from (10536)@172.17.6.22:50606 with proposal 1
I1109 18:19:14.840972 28055 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 883432ns
I1109 18:19:14.841007 28055 replica.cpp:345] Persisted promised to 1
I1109 18:19:14.843297 28061 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1109 18:19:14.845017 28057 replica.cpp:391] Replica received explicit promise request from (10537)@172.17.6.22:50606 for position 0 with proposal 2
I1109 18:19:14.845679 28057 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 608802ns
I1109 18:19:14.845710 28057 replica.cpp:715] Persisted action at 0
I1109 18:19:14.847141 28058 replica.cpp:540] Replica received write request for position 0 from (10538)@172.17.6.22:50606
I1109 18:19:14.847261 28058 leveldb.cpp:438] Reading position from leveldb took 70529ns
I1109 18:19:14.847970 28058 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 656240ns
I1109 18:19:14.848000 28058 replica.cpp:715] Persisted action at 0
I1109 18:19:14.848893 28057 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1109 18:19:14.849406 28057 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 497209ns
I1109 18:19:14.849433 28057 replica.cpp:715] Persisted action at 0
I1109 18:19:14.849452 28057 replica.cpp:700] Replica learned NOP action at position 0
I1109 18:19:14.850246 28060 log.cpp:677] Writer started with ending position 0
I1109 18:19:14.851550 28063 leveldb.cpp:438] Reading position from leveldb took 87110ns
I1109 18:19:14.852856 28063 registrar.cpp:342] Successfully fetched the registry (0B) in 16.821248ms
I1109 18:19:14.853036 28063 registrar.cpp:441] Applied 1 operations in 53986ns; attempting to update the 'registry'
I1109 18:19:14.853752 28055 log.cpp:685] Attempting to append 174 bytes to the log
I1109 18:19:14.854017 28052 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1109 18:19:14.855149 28064 replica.cpp:540] Replica received write request for position 1 from (10539)@172.17.6.22:50606
I1109 18:19:14.855898 28064 leveldb.cpp:343] Persisting action (193 bytes) to leveldb took 713955ns
I1109 18:19:14.855937 28064 replica.cpp:715] Persisted action at 1
I1109 18:19:14.856784 28064 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1109 18:19:14.857281 28064 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 491755ns
I1109 18:19:14.857457 28064 replica.cpp:715] Persisted action at 1
I1109 18:19:14.857620 28064 replica.cpp:700] Replica learned APPEND action at position 1
I1109 18:19:14.859885 28064 registrar.cpp:486] Successfully updated the 'registry' in 6.776064ms
I1109 18:19:14.860121 28064 registrar.cpp:372] Successfully recovered registrar
I1109 18:19:14.859983 28057 log.cpp:704] Attempting to truncate the log to 1
I1109 18:19:14.861029 28051 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1109 18:19:14.861323 28064 master.cpp:1416] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1109 18:19:14.862046 28064 replica.cpp:540] Replica received write request for position 2 from (10540)@172.17.6.22:50606
I1109 18:19:14.862593 28064 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 516696ns
I1109 18:19:14.862623 28064 replica.cpp:715] Persisted action at 2
I1109 18:19:14.863713 28064 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1109 18:19:14.864279 28064 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 546916ns
I1109 18:19:14.864347 28064 leveldb.cpp:401] Deleting ~1 keys from leveldb took 53545ns
I1109 18:19:14.864375 28064 replica.cpp:715] Persisted action at 2
I1109 18:19:14.864403 28064 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1109 18:19:14.873769 28032 scheduler.cpp:156] Version: 0.26.0
I1109 18:19:14.874977 28050 scheduler.cpp:238] New master detected at master@172.17.6.22:50606
I1109 18:19:14.876523 28065 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.22:50606
I1109 18:19:14.878911 28060 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:19:14.879180 28060 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.22:44414
I1109 18:19:14.879729 28060 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1109 18:19:14.879760 28060 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1109 18:19:14.880205 28060 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1109 18:19:14.881232 28058 master.hpp:1439] Sending heartbeat to cf0250c9-b3b4-4b28-961d-5fcce836f557-0000
I1109 18:19:14.882680 28054 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.22:50606
I1109 18:19:14.883527 28056 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.22:50606
I1109 18:19:14.883649 28060 hierarchical.cpp:185] Added framework cf0250c9-b3b4-4b28-961d-5fcce836f557-0000
I1109 18:19:14.883723 28060 hierarchical.cpp:971] No resources available to allocate!
I1109 18:19:14.883769 28060 hierarchical.cpp:1064] No inverse offers to send out!
I1109 18:19:14.883800 28060 hierarchical.cpp:870] Performed allocation for 0 slaves in 136473ns
I1109 18:19:14.884313 28056 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1109 18:19:14.885373 28058 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.22:50606
I1109 18:19:14.887362 28058 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:19:14.887614 28058 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.22:44415
I1109 18:19:14.887869 28058 master.cpp:2615] Processing REQUEST call for framework cf0250c9-b3b4-4b28-961d-5fcce836f557-0000 (default)
I1109 18:19:14.888396 28058 hierarchical.cpp:449] Received resource request from framework cf0250c9-b3b4-4b28-961d-5fcce836f557-0000
I1109 18:19:14.888937 28032 master.cpp:922] Master terminating
I1109 18:19:14.889565 28053 hierarchical.cpp:220] Removed framework cf0250c9-b3b4-4b28-961d-5fcce836f557-0000
E1109 18:19:14.902287 28053 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (89 ms)
[----------] 22 tests from ContentType/SchedulerTest (4975 ms total)
[----------] Global test environment tear-down
[==========] 825 tests from 112 test cases ran. (334029 ms total)
[ PASSED ] 824 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DiskQuotaTest.ResourceStatistics
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1447091099-24193
Untagged: mesos-1447091099-24193:latest
Deleted: 0c95a9369e31f098c203e850d1d78c2bb5566d233e429e4fc3becdda29b3eac1
Deleted: e52b925874630209011f0dc8921b9e5e88baddaff9f35ff458b73057278f6aa1
Deleted: 4357ef3e2919b863b4c4706c31b169e72eea897b6409153920e47167c12c1f91
Deleted: 992c9bde8cc47c26e838bf2c6ec5efa2ec2822ec7125ee7da9c3e87c55626805
Deleted: 19de483c0c42bca345dd8362a7fb41dd384b852703da25bb1fa0ece8ab4060d0
Deleted: 2dc0013313cb881bb62a3cfcfded2d26f9806d451b081642a946916462e32be7
Deleted: 7a61d88d21e200d6b38b3e5340540001683de738ecdd36449d4ead30fe873fe8
Deleted: eb858df8aa01085a3701a365c574306428e74c3a49dd63e046f0244a48048fed
Deleted: 447a7c29d2c3577ac01f65b7a7cfbe24abf222cccb922c88d60a34ae3d248946
Deleted: c7114bb6a962c01eab743cddf38eb40828474fe27c68ec57e7a2e47c96c8a343
Deleted: cc4a3210e4add8d451072f1663db1bc9dc0b14fa54a182dc5ffb49c93fb3b380
Deleted: b537952861642ae9681dcc9a5315d92acca144ff5d47c15d97a64228ae9352f7
Deleted: d47709e860ca4a8272154dcc9075349b82abac5476e7b3a4be9f56382f230488
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1205
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1205/>
Re: Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1204
Posted by Vinod Kone <vi...@gmail.com>.
On Mon, Nov 9, 2015 at 10:59 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> [ FAILED ] OversubscriptionTest.QoSCorrectionKill
>
hmm. this seems new. @nik mind taking a look?
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1204
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1204/changes>
Changes:
[vinodkone] Renamed slave to agent in v1/scheduler.proto.
[joris.van.remoortere] Fixed a typo in ssl.md.
------------------------------------------
[...truncated 139554 lines...]
I1109 18:59:56.487722 28064 slave.cpp:2034] Shutting down framework e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000
I1109 18:59:56.487789 28064 slave.cpp:3657] Cleaning up executor 'default' of framework e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000 at executor(132)@172.17.6.36:39887
I1109 18:59:56.488175 28064 slave.cpp:3745] Cleaning up framework e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000
I1109 18:59:56.488788 28064 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_aLjU3Y/slaves/e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-S0/frameworks/e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000/executors/default/runs/c0bf45b9-004a-4b8c-987e-b9e2adece8bf' for gc 6.99999435238519days in the future
I1109 18:59:56.489051 28071 status_update_manager.cpp:284] Closing status update streams for framework e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000
I1109 18:59:56.489112 28071 status_update_manager.cpp:530] Cleaning up status update stream for task 138d0936-518e-4dbe-bcd6-ba60107e3eba of framework e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000
I1109 18:59:56.490661 28064 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_aLjU3Y/slaves/e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-S0/frameworks/e7aa62fe-5080-4cd6-ab3a-dbf62bdff1ce-0000/executors/default' for gc 6.99999435041778days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (197 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1109 18:59:56.519264 28040 leveldb.cpp:176] Opened db in 7.82686ms
I1109 18:59:56.522963 28040 leveldb.cpp:183] Compacted db in 3.653436ms
I1109 18:59:56.523061 28040 leveldb.cpp:198] Created db iterator in 42607ns
I1109 18:59:56.523090 28040 leveldb.cpp:204] Seeked to beginning of db in 13525ns
I1109 18:59:56.523108 28040 leveldb.cpp:273] Iterated through 0 keys in the db in 8834ns
I1109 18:59:56.523171 28040 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1109 18:59:56.523845 28070 recover.cpp:449] Starting replica recovery
I1109 18:59:56.524117 28070 recover.cpp:475] Replica is in EMPTY status
I1109 18:59:56.525949 28071 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10509)@172.17.6.36:39887
I1109 18:59:56.526348 28058 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1109 18:59:56.526985 28060 recover.cpp:566] Updating replica status to STARTING
I1109 18:59:56.529836 28058 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 763791ns
I1109 18:59:56.529883 28058 replica.cpp:323] Persisted replica status to STARTING
I1109 18:59:56.530069 28058 recover.cpp:475] Replica is in STARTING status
I1109 18:59:56.531492 28061 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10510)@172.17.6.36:39887
I1109 18:59:56.531846 28061 recover.cpp:195] Received a recover response from a replica in STARTING status
I1109 18:59:56.532402 28061 recover.cpp:566] Updating replica status to VOTING
I1109 18:59:56.532965 28061 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 410631ns
I1109 18:59:56.533016 28061 replica.cpp:323] Persisted replica status to VOTING
I1109 18:59:56.533133 28069 recover.cpp:580] Successfully joined the Paxos group
I1109 18:59:56.533341 28069 recover.cpp:464] Recover process terminated
I1109 18:59:56.534960 28060 master.cpp:367] Master 021f3f08-3f7a-4b13-bc45-167e768a8053 (79ac545d9f34) started on 172.17.6.36:39887
I1109 18:59:56.534998 28060 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/oMH1Bz/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/oMH1Bz/master" --zk_session_timeout="10secs"
I1109 18:59:56.535372 28060 master.cpp:416] Master allowing unauthenticated frameworks to register
I1109 18:59:56.535392 28060 master.cpp:419] Master only allowing authenticated slaves to register
I1109 18:59:56.535403 28060 credentials.hpp:37] Loading credentials for authentication from '/tmp/oMH1Bz/credentials'
I1109 18:59:56.535733 28060 master.cpp:458] Using default 'crammd5' authenticator
I1109 18:59:56.535892 28060 master.cpp:495] Authorization enabled
I1109 18:59:56.537113 28067 hierarchical.cpp:140] Initialized hierarchical allocator process
I1109 18:59:56.537724 28070 whitelist_watcher.cpp:79] No whitelist given
I1109 18:59:56.539988 28060 master.cpp:1606] The newly elected leader is master@172.17.6.36:39887 with id 021f3f08-3f7a-4b13-bc45-167e768a8053
I1109 18:59:56.540107 28060 master.cpp:1619] Elected as the leading master!
I1109 18:59:56.540226 28060 master.cpp:1379] Recovering from registrar
I1109 18:59:56.540599 28060 registrar.cpp:309] Recovering registrar
I1109 18:59:56.541520 28061 log.cpp:661] Attempting to start the writer
I1109 18:59:56.543189 28061 replica.cpp:496] Replica received implicit promise request from (10511)@172.17.6.36:39887 with proposal 1
I1109 18:59:56.543972 28061 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 689905ns
I1109 18:59:56.544003 28061 replica.cpp:345] Persisted promised to 1
I1109 18:59:56.544659 28066 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1109 18:59:56.546267 28060 replica.cpp:391] Replica received explicit promise request from (10512)@172.17.6.36:39887 for position 0 with proposal 2
I1109 18:59:56.546856 28060 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 516626ns
I1109 18:59:56.546888 28060 replica.cpp:715] Persisted action at 0
I1109 18:59:56.548126 28059 replica.cpp:540] Replica received write request for position 0 from (10513)@172.17.6.36:39887
I1109 18:59:56.548215 28059 leveldb.cpp:438] Reading position from leveldb took 54452ns
I1109 18:59:56.548722 28059 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 466995ns
I1109 18:59:56.548748 28059 replica.cpp:715] Persisted action at 0
I1109 18:59:56.550117 28070 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1109 18:59:56.550966 28070 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 827009ns
I1109 18:59:56.550995 28070 replica.cpp:715] Persisted action at 0
I1109 18:59:56.551018 28070 replica.cpp:700] Replica learned NOP action at position 0
I1109 18:59:56.551893 28070 log.cpp:677] Writer started with ending position 0
I1109 18:59:56.553234 28068 leveldb.cpp:438] Reading position from leveldb took 70264ns
I1109 18:59:56.554385 28072 registrar.cpp:342] Successfully fetched the registry (0B) in 13.47712ms
I1109 18:59:56.554520 28072 registrar.cpp:441] Applied 1 operations in 46083ns; attempting to update the 'registry'
I1109 18:59:56.555325 28067 log.cpp:685] Attempting to append 174 bytes to the log
I1109 18:59:56.555464 28066 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1109 18:59:56.556504 28061 replica.cpp:540] Replica received write request for position 1 from (10514)@172.17.6.36:39887
I1109 18:59:56.557126 28061 leveldb.cpp:343] Persisting action (193 bytes) to leveldb took 583928ns
I1109 18:59:56.557157 28061 replica.cpp:715] Persisted action at 1
I1109 18:59:56.563767 28068 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1109 18:59:56.564493 28068 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 692183ns
I1109 18:59:56.564528 28068 replica.cpp:715] Persisted action at 1
I1109 18:59:56.564558 28068 replica.cpp:700] Replica learned APPEND action at position 1
I1109 18:59:56.565814 28064 registrar.cpp:486] Successfully updated the 'registry' in 11.22176ms
I1109 18:59:56.565976 28064 registrar.cpp:372] Successfully recovered registrar
I1109 18:59:56.566141 28069 log.cpp:704] Attempting to truncate the log to 1
I1109 18:59:56.566330 28069 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1109 18:59:56.566340 28064 master.cpp:1416] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1109 18:59:56.567818 28061 replica.cpp:540] Replica received write request for position 2 from (10515)@172.17.6.36:39887
I1109 18:59:56.568387 28061 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 534983ns
I1109 18:59:56.568415 28061 replica.cpp:715] Persisted action at 2
I1109 18:59:56.569327 28066 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1109 18:59:56.569664 28066 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 319159ns
I1109 18:59:56.569715 28066 leveldb.cpp:401] Deleting ~1 keys from leveldb took 36851ns
I1109 18:59:56.569737 28066 replica.cpp:715] Persisted action at 2
I1109 18:59:56.569763 28066 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1109 18:59:56.577781 28040 scheduler.cpp:156] Version: 0.26.0
I1109 18:59:56.578624 28059 scheduler.cpp:238] New master detected at master@172.17.6.36:39887
I1109 18:59:56.580088 28063 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.36:39887
I1109 18:59:56.582770 28061 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:59:56.583071 28061 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.36:42147
I1109 18:59:56.583353 28061 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1109 18:59:56.583389 28061 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1109 18:59:56.583789 28071 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1109 18:59:56.584239 28058 hierarchical.cpp:185] Added framework 021f3f08-3f7a-4b13-bc45-167e768a8053-0000
I1109 18:59:56.584379 28058 hierarchical.cpp:971] No resources available to allocate!
I1109 18:59:56.584527 28058 hierarchical.cpp:1064] No inverse offers to send out!
I1109 18:59:56.584637 28058 hierarchical.cpp:870] Performed allocation for 0 slaves in 308012ns
I1109 18:59:56.584750 28058 master.hpp:1439] Sending heartbeat to 021f3f08-3f7a-4b13-bc45-167e768a8053-0000
I1109 18:59:56.585731 28061 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.36:39887
I1109 18:59:56.587294 28061 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.36:39887
I1109 18:59:56.589720 28058 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.36:39887
I1109 18:59:56.590716 28058 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1109 18:59:56.590876 28058 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:59:56.591145 28058 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.36:42148
I1109 18:59:56.591305 28058 master.cpp:2615] Processing REQUEST call for framework 021f3f08-3f7a-4b13-bc45-167e768a8053-0000 (default)
I1109 18:59:56.591851 28058 hierarchical.cpp:449] Received resource request from framework 021f3f08-3f7a-4b13-bc45-167e768a8053-0000
I1109 18:59:56.592370 28069 master.cpp:922] Master terminating
I1109 18:59:56.592947 28069 hierarchical.cpp:220] Removed framework 021f3f08-3f7a-4b13-bc45-167e768a8053-0000
E1109 18:59:56.607378 28061 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (135 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1109 18:59:56.647857 28040 leveldb.cpp:176] Opened db in 3.060464ms
I1109 18:59:56.648789 28040 leveldb.cpp:183] Compacted db in 893419ns
I1109 18:59:56.648841 28040 leveldb.cpp:198] Created db iterator in 33949ns
I1109 18:59:56.648866 28040 leveldb.cpp:204] Seeked to beginning of db in 10731ns
I1109 18:59:56.648882 28040 leveldb.cpp:273] Iterated through 0 keys in the db in 8910ns
I1109 18:59:56.648947 28040 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1109 18:59:56.649551 28059 recover.cpp:449] Starting replica recovery
I1109 18:59:56.649834 28072 recover.cpp:475] Replica is in EMPTY status
I1109 18:59:56.650982 28059 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10520)@172.17.6.36:39887
I1109 18:59:56.651407 28071 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1109 18:59:56.651818 28059 recover.cpp:566] Updating replica status to STARTING
I1109 18:59:56.652534 28071 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 626560ns
I1109 18:59:56.652565 28071 replica.cpp:323] Persisted replica status to STARTING
I1109 18:59:56.652742 28071 recover.cpp:475] Replica is in STARTING status
I1109 18:59:56.654252 28069 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10521)@172.17.6.36:39887
I1109 18:59:56.655293 28068 recover.cpp:195] Received a recover response from a replica in STARTING status
I1109 18:59:56.655711 28069 master.cpp:367] Master 84ab6c29-629a-4a73-94c5-95e18337223c (79ac545d9f34) started on 172.17.6.36:39887
I1109 18:59:56.655751 28069 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/y1h3lM/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/y1h3lM/master" --zk_session_timeout="10secs"
I1109 18:59:56.656108 28069 master.cpp:416] Master allowing unauthenticated frameworks to register
I1109 18:59:56.656121 28069 master.cpp:419] Master only allowing authenticated slaves to register
I1109 18:59:56.656129 28069 credentials.hpp:37] Loading credentials for authentication from '/tmp/y1h3lM/credentials'
I1109 18:59:56.656431 28069 master.cpp:458] Using default 'crammd5' authenticator
I1109 18:59:56.656569 28069 master.cpp:495] Authorization enabled
I1109 18:59:56.656905 28061 hierarchical.cpp:140] Initialized hierarchical allocator process
I1109 18:59:56.656985 28061 whitelist_watcher.cpp:79] No whitelist given
I1109 18:59:56.658756 28069 master.cpp:1606] The newly elected leader is master@172.17.6.36:39887 with id 84ab6c29-629a-4a73-94c5-95e18337223c
I1109 18:59:56.658798 28069 master.cpp:1619] Elected as the leading master!
I1109 18:59:56.658815 28069 master.cpp:1379] Recovering from registrar
I1109 18:59:56.659037 28073 registrar.cpp:309] Recovering registrar
I1109 18:59:56.660130 28067 recover.cpp:566] Updating replica status to VOTING
I1109 18:59:56.660790 28067 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 496896ns
I1109 18:59:56.660821 28067 replica.cpp:323] Persisted replica status to VOTING
I1109 18:59:56.660953 28067 recover.cpp:580] Successfully joined the Paxos group
I1109 18:59:56.661159 28067 recover.cpp:464] Recover process terminated
I1109 18:59:56.661597 28063 log.cpp:661] Attempting to start the writer
I1109 18:59:56.663177 28072 replica.cpp:496] Replica received implicit promise request from (10522)@172.17.6.36:39887 with proposal 1
I1109 18:59:56.663614 28072 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 414890ns
I1109 18:59:56.663640 28072 replica.cpp:345] Persisted promised to 1
I1109 18:59:56.664268 28059 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1109 18:59:56.665726 28073 replica.cpp:391] Replica received explicit promise request from (10523)@172.17.6.36:39887 for position 0 with proposal 2
I1109 18:59:56.666266 28073 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 510131ns
I1109 18:59:56.666295 28073 replica.cpp:715] Persisted action at 0
I1109 18:59:56.667207 28068 replica.cpp:540] Replica received write request for position 0 from (10524)@172.17.6.36:39887
I1109 18:59:56.667268 28068 leveldb.cpp:438] Reading position from leveldb took 43241ns
I1109 18:59:56.667685 28068 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 381879ns
I1109 18:59:56.667711 28068 replica.cpp:715] Persisted action at 0
I1109 18:59:56.668283 28059 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1109 18:59:56.668738 28059 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 437145ns
I1109 18:59:56.668763 28059 replica.cpp:715] Persisted action at 0
I1109 18:59:56.668782 28059 replica.cpp:700] Replica learned NOP action at position 0
I1109 18:59:56.669558 28059 log.cpp:677] Writer started with ending position 0
I1109 18:59:56.670886 28059 leveldb.cpp:438] Reading position from leveldb took 45160ns
I1109 18:59:56.672060 28073 registrar.cpp:342] Successfully fetched the registry (0B) in 12.979968ms
I1109 18:59:56.672196 28073 registrar.cpp:441] Applied 1 operations in 47094ns; attempting to update the 'registry'
I1109 18:59:56.673429 28059 log.cpp:685] Attempting to append 174 bytes to the log
I1109 18:59:56.673635 28059 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1109 18:59:56.674765 28061 replica.cpp:540] Replica received write request for position 1 from (10525)@172.17.6.36:39887
I1109 18:59:56.675312 28061 leveldb.cpp:343] Persisting action (193 bytes) to leveldb took 518724ns
I1109 18:59:56.675340 28061 replica.cpp:715] Persisted action at 1
I1109 18:59:56.676193 28061 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1109 18:59:56.676558 28061 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 349775ns
I1109 18:59:56.676586 28061 replica.cpp:715] Persisted action at 1
I1109 18:59:56.676609 28061 replica.cpp:700] Replica learned APPEND action at position 1
I1109 18:59:56.677714 28062 registrar.cpp:486] Successfully updated the 'registry' in 5.424128ms
I1109 18:59:56.677848 28062 registrar.cpp:372] Successfully recovered registrar
I1109 18:59:56.677980 28069 log.cpp:704] Attempting to truncate the log to 1
I1109 18:59:56.678113 28060 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1109 18:59:56.678371 28062 master.cpp:1416] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1109 18:59:56.679250 28065 replica.cpp:540] Replica received write request for position 2 from (10526)@172.17.6.36:39887
I1109 18:59:56.679709 28065 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 432867ns
I1109 18:59:56.679736 28065 replica.cpp:715] Persisted action at 2
I1109 18:59:56.680615 28065 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1109 18:59:56.681241 28065 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 613278ns
I1109 18:59:56.681300 28065 leveldb.cpp:401] Deleting ~1 keys from leveldb took 44397ns
I1109 18:59:56.681319 28065 replica.cpp:715] Persisted action at 2
I1109 18:59:56.681340 28065 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1109 18:59:56.690074 28040 scheduler.cpp:156] Version: 0.26.0
I1109 18:59:56.690959 28072 scheduler.cpp:238] New master detected at master@172.17.6.36:39887
I1109 18:59:56.692618 28063 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.36:39887
I1109 18:59:56.695298 28062 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:59:56.695516 28062 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.36:42149
I1109 18:59:56.695924 28062 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1109 18:59:56.695969 28062 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1109 18:59:56.696751 28062 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1109 18:59:56.698093 28062 hierarchical.cpp:185] Added framework 84ab6c29-629a-4a73-94c5-95e18337223c-0000
I1109 18:59:56.698151 28062 hierarchical.cpp:971] No resources available to allocate!
I1109 18:59:56.698186 28062 hierarchical.cpp:1064] No inverse offers to send out!
I1109 18:59:56.698210 28062 hierarchical.cpp:870] Performed allocation for 0 slaves in 100387ns
I1109 18:59:56.698237 28062 master.hpp:1439] Sending heartbeat to 84ab6c29-629a-4a73-94c5-95e18337223c-0000
I1109 18:59:56.699242 28059 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.36:39887
I1109 18:59:56.700541 28072 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.36:39887
I1109 18:59:56.701118 28072 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.36:39887
I1109 18:59:56.702206 28067 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1109 18:59:56.703196 28072 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1109 18:59:56.703385 28072 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.36:42150
I1109 18:59:56.703703 28072 master.cpp:2615] Processing REQUEST call for framework 84ab6c29-629a-4a73-94c5-95e18337223c-0000 (default)
I1109 18:59:56.703917 28065 hierarchical.cpp:449] Received resource request from framework 84ab6c29-629a-4a73-94c5-95e18337223c-0000
I1109 18:59:56.704206 28040 master.cpp:922] Master terminating
I1109 18:59:56.704586 28069 hierarchical.cpp:220] Removed framework 84ab6c29-629a-4a73-94c5-95e18337223c-0000
E1109 18:59:56.715015 28071 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (87 ms)
[----------] 22 tests from ContentType/SchedulerTest (5363 ms total)
[----------] Global test environment tear-down
[==========] 825 tests from 112 test cases ran. (359233 ms total)
[ PASSED ] 823 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] FetcherCacheHttpTest.HttpMixed
[ FAILED ] OversubscriptionTest.QoSCorrectionKill
2 FAILED TESTS
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
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-1447093442-7323
Untagged: mesos-1447093442-7323:latest
Deleted: b7029ad7e300859b6d01cdf972d607b49b81c1c83996fe192161a4b138977713
Deleted: dcfd05364fa1233f61025425aec734ee4fc35056f4a24f294b4652b5c945c4e4
Deleted: d8bd824a86faa4d7311f84a925d001e924570321afb9260628a7dafde90729a3
Deleted: b612f45e819a59431d7b59dde27dcf35701c563eecf38b53cbdb6a6ecf494d89
Deleted: 161e3751f75b0c38a199a03bb3e4dbfd39e9f924c1b9bcf4547dd433583839b4
Deleted: e37e6e6d23d26f88bf219427d701fff1d5d703287f793423654bf599d52010bd
Deleted: 592d87b4716b2d4fd54e871f9c55747a5b5d07c1c25025266e703b86a4e7ff3c
Deleted: 78b11155e8d6396c7e668b067e60bce4621c23eb129f042147c6ce3525e0a0a2
Deleted: 1da7890f50d2b1c0690aea94a4f336f44217b9a2ebf33aeea47d44e46cc1746c
Deleted: 793adc22dabea3233fdd49c675e2a9a41798b570bd1ca24941ee85f6e0709159
Deleted: a14e296c4636ff4b2e646115348cb5fbe03c03349b865b0d53168043ef7f1382
Deleted: 96da9072fa1d8199a6768b636b113f6c36cb1621048390d098becf4ca8ef624d
Deleted: 0c282aa1c61a1c142ff59ee30de2febc78928fe76bc3e51954bdd3c5ab74ab03
Build step 'Execute shell' marked build as failure