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 2016/01/11 10:42:58 UTC
Build failed in Jenkins: mesos-reviewbot #10710
See <https://builds.apache.org/job/mesos-reviewbot/10710/>
------------------------------------------
[...truncated 165103 lines...]
I0111 09:42:42.856871 31444 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 09:42:42.857420 31450 recover.cpp:564] Updating replica status to STARTING
I0111 09:42:42.858393 31444 master.cpp:372] Master bf4b8375-1184-4809-afe4-372a8b38d6e9 (231d97326da2) started on 172.17.0.3:46527
I0111 09:42:42.858423 31444 master.cpp:374] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/UJzK0T/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/UJzK0T/master" --zk_session_timeout="10secs"
I0111 09:42:42.858858 31444 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 09:42:42.858872 31444 master.cpp:424] Master only allowing authenticated slaves to register
I0111 09:42:42.858883 31444 credentials.hpp:35] Loading credentials for authentication from '/tmp/UJzK0T/credentials'
I0111 09:42:42.859321 31444 master.cpp:464] Using default 'crammd5' authenticator
I0111 09:42:42.859493 31444 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 09:42:42.859642 31444 master.cpp:567] Authorization enabled
I0111 09:42:42.859858 31437 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 09:42:42.859863 31438 whitelist_watcher.cpp:77] No whitelist given
I0111 09:42:42.861814 31447 master.cpp:1715] The newly elected leader is master@172.17.0.3:46527 with id bf4b8375-1184-4809-afe4-372a8b38d6e9
I0111 09:42:42.861858 31447 master.cpp:1728] Elected as the leading master!
I0111 09:42:42.861891 31447 master.cpp:1473] Recovering from registrar
I0111 09:42:42.862118 31435 registrar.cpp:307] Recovering registrar
I0111 09:42:42.895870 31443 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.298926ms
I0111 09:42:42.895912 31443 replica.cpp:320] Persisted replica status to STARTING
I0111 09:42:42.896168 31442 recover.cpp:473] Replica is in STARTING status
I0111 09:42:42.897331 31446 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13470)@172.17.0.3:46527
I0111 09:42:42.897686 31447 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 09:42:42.898206 31437 recover.cpp:564] Updating replica status to VOTING
I0111 09:42:42.937630 31440 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.213305ms
I0111 09:42:42.937669 31440 replica.cpp:320] Persisted replica status to VOTING
I0111 09:42:42.937790 31441 recover.cpp:578] Successfully joined the Paxos group
I0111 09:42:42.938045 31441 recover.cpp:462] Recover process terminated
I0111 09:42:42.938628 31445 log.cpp:659] Attempting to start the writer
I0111 09:42:42.939901 31439 replica.cpp:493] Replica received implicit promise request from (13471)@172.17.0.3:46527 with proposal 1
I0111 09:42:42.979406 31439 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 39.468403ms
I0111 09:42:42.979450 31439 replica.cpp:342] Persisted promised to 1
I0111 09:42:42.980340 31446 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 09:42:42.981861 31447 replica.cpp:388] Replica received explicit promise request from (13472)@172.17.0.3:46527 for position 0 with proposal 2
I0111 09:42:43.021185 31447 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 39.271961ms
I0111 09:42:43.021224 31447 replica.cpp:712] Persisted action at 0
I0111 09:42:43.022415 31440 replica.cpp:537] Replica received write request for position 0 from (13473)@172.17.0.3:46527
I0111 09:42:43.022558 31440 leveldb.cpp:436] Reading position from leveldb took 42428ns
I0111 09:42:43.054584 31440 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.964489ms
I0111 09:42:43.054625 31440 replica.cpp:712] Persisted action at 0
I0111 09:42:43.055371 31436 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 09:42:43.088009 31436 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.61244ms
I0111 09:42:43.088038 31436 replica.cpp:712] Persisted action at 0
I0111 09:42:43.088053 31436 replica.cpp:697] Replica learned NOP action at position 0
I0111 09:42:43.088737 31435 log.cpp:675] Writer started with ending position 0
I0111 09:42:43.089962 31446 leveldb.cpp:436] Reading position from leveldb took 32839ns
I0111 09:42:43.091025 31437 registrar.cpp:340] Successfully fetched the registry (0B) in 228.818944ms
I0111 09:42:43.091235 31437 registrar.cpp:439] Applied 1 operations in 32821ns; attempting to update the 'registry'
I0111 09:42:43.092028 31439 log.cpp:683] Attempting to append 170 bytes to the log
I0111 09:42:43.092186 31444 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 09:42:43.092986 31438 replica.cpp:537] Replica received write request for position 1 from (13474)@172.17.0.3:46527
I0111 09:42:43.129920 31438 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 36.889638ms
I0111 09:42:43.129966 31438 replica.cpp:712] Persisted action at 1
I0111 09:42:43.130890 31448 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 09:42:43.163445 31448 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.518038ms
I0111 09:42:43.163491 31448 replica.cpp:712] Persisted action at 1
I0111 09:42:43.163514 31448 replica.cpp:697] Replica learned APPEND action at position 1
I0111 09:42:43.164649 31441 registrar.cpp:484] Successfully updated the 'registry' in 73.341184ms
I0111 09:42:43.164837 31441 registrar.cpp:370] Successfully recovered registrar
I0111 09:42:43.164901 31439 log.cpp:702] Attempting to truncate the log to 1
I0111 09:42:43.165042 31443 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 09:42:43.165441 31449 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 09:42:43.165472 31435 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 09:42:43.165935 31446 replica.cpp:537] Replica received write request for position 2 from (13475)@172.17.0.3:46527
I0111 09:42:43.204953 31446 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.982147ms
I0111 09:42:43.204984 31446 replica.cpp:712] Persisted action at 2
I0111 09:42:43.205857 31441 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 09:42:43.238546 31441 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.649013ms
I0111 09:42:43.238631 31441 leveldb.cpp:399] Deleting ~1 keys from leveldb took 42166ns
I0111 09:42:43.238657 31441 replica.cpp:712] Persisted action at 2
I0111 09:42:43.238680 31441 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 09:42:43.246588 31416 scheduler.cpp:154] Version: 0.27.0
I0111 09:42:43.247264 31445 scheduler.cpp:236] New master detected at master@172.17.0.3:46527
I0111 09:42:43.248550 31435 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:46527
I0111 09:42:43.250656 31443 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 09:42:43.251704 31440 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:39742
I0111 09:42:43.251917 31440 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 09:42:43.251982 31440 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 09:42:43.252352 31440 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 09:42:43.252717 31436 hierarchical.cpp:260] Added framework bf4b8375-1184-4809-afe4-372a8b38d6e9-0000
I0111 09:42:43.252786 31436 hierarchical.cpp:1329] No resources available to allocate!
I0111 09:42:43.252820 31444 master.hpp:1657] Sending heartbeat to bf4b8375-1184-4809-afe4-372a8b38d6e9-0000
I0111 09:42:43.252972 31436 hierarchical.cpp:1423] No inverse offers to send out!
I0111 09:42:43.253007 31436 hierarchical.cpp:1079] Performed allocation for 0 slaves in 259437ns
I0111 09:42:43.253919 31442 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:46527
I0111 09:42:43.254472 31442 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:46527
I0111 09:42:43.255264 31435 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 09:42:43.255360 31442 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:46527
I0111 09:42:43.257042 31444 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 09:42:43.257557 31445 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:39743
I0111 09:42:43.257683 31445 master.cpp:2722] Processing REQUEST call for framework bf4b8375-1184-4809-afe4-372a8b38d6e9-0000 (default)
I0111 09:42:43.257863 31441 hierarchical.cpp:579] Received resource request from framework bf4b8375-1184-4809-afe4-372a8b38d6e9-0000
I0111 09:42:43.258278 31449 master.cpp:1016] Master terminating
I0111 09:42:43.258574 31439 hierarchical.cpp:321] Removed framework bf4b8375-1184-4809-afe4-372a8b38d6e9-0000
E0111 09:42:43.259583 31437 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (529 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
2016-01-11 09:42:43,355:31416(0x2b2ec78c9700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:34294] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0111 09:42:43.409641 31416 leveldb.cpp:174] Opened db in 144.396826ms
I0111 09:42:43.454983 31416 leveldb.cpp:181] Compacted db in 45.299211ms
I0111 09:42:43.455052 31416 leveldb.cpp:196] Created db iterator in 19339ns
I0111 09:42:43.455072 31416 leveldb.cpp:202] Seeked to beginning of db in 2003ns
I0111 09:42:43.455085 31416 leveldb.cpp:271] Iterated through 0 keys in the db in 330ns
I0111 09:42:43.455152 31416 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0111 09:42:43.455612 31442 recover.cpp:447] Starting replica recovery
I0111 09:42:43.455978 31442 recover.cpp:473] Replica is in EMPTY status
I0111 09:42:43.457159 31440 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13483)@172.17.0.3:46527
I0111 09:42:43.457684 31443 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 09:42:43.458281 31442 recover.cpp:564] Updating replica status to STARTING
I0111 09:42:43.459789 31437 master.cpp:372] Master c81dca28-494d-45b0-891f-5bdf32a780d3 (231d97326da2) started on 172.17.0.3:46527
I0111 09:42:43.459818 31437 master.cpp:374] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/wQTRve/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/wQTRve/master" --zk_session_timeout="10secs"
I0111 09:42:43.460247 31437 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 09:42:43.460270 31437 master.cpp:424] Master only allowing authenticated slaves to register
I0111 09:42:43.460288 31437 credentials.hpp:35] Loading credentials for authentication from '/tmp/wQTRve/credentials'
I0111 09:42:43.460633 31437 master.cpp:464] Using default 'crammd5' authenticator
I0111 09:42:43.460791 31437 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 09:42:43.460979 31437 master.cpp:567] Authorization enabled
I0111 09:42:43.461236 31445 whitelist_watcher.cpp:77] No whitelist given
I0111 09:42:43.461244 31441 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 09:42:43.463049 31438 master.cpp:1715] The newly elected leader is master@172.17.0.3:46527 with id c81dca28-494d-45b0-891f-5bdf32a780d3
I0111 09:42:43.463085 31438 master.cpp:1728] Elected as the leading master!
I0111 09:42:43.463107 31438 master.cpp:1473] Recovering from registrar
I0111 09:42:43.463274 31442 registrar.cpp:307] Recovering registrar
I0111 09:42:43.484983 31446 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.478758ms
I0111 09:42:43.485028 31446 replica.cpp:320] Persisted replica status to STARTING
I0111 09:42:43.485294 31440 recover.cpp:473] Replica is in STARTING status
I0111 09:42:43.486416 31443 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13485)@172.17.0.3:46527
I0111 09:42:43.486857 31450 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 09:42:43.487427 31446 recover.cpp:564] Updating replica status to VOTING
I0111 09:42:43.513226 31436 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 25.640331ms
I0111 09:42:43.513265 31436 replica.cpp:320] Persisted replica status to VOTING
I0111 09:42:43.513414 31436 recover.cpp:578] Successfully joined the Paxos group
I0111 09:42:43.513708 31436 recover.cpp:462] Recover process terminated
I0111 09:42:43.514297 31442 log.cpp:659] Attempting to start the writer
I0111 09:42:43.515714 31442 replica.cpp:493] Replica received implicit promise request from (13486)@172.17.0.3:46527 with proposal 1
I0111 09:42:43.538877 31442 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 23.120177ms
I0111 09:42:43.538913 31442 replica.cpp:342] Persisted promised to 1
I0111 09:42:43.539774 31448 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 09:42:43.541287 31440 replica.cpp:388] Replica received explicit promise request from (13487)@172.17.0.3:46527 for position 0 with proposal 2
I0111 09:42:43.572360 31440 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.025576ms
I0111 09:42:43.572401 31440 replica.cpp:712] Persisted action at 0
I0111 09:42:43.574477 31440 replica.cpp:537] Replica received write request for position 0 from (13488)@172.17.0.3:46527
I0111 09:42:43.574550 31440 leveldb.cpp:436] Reading position from leveldb took 32681ns
I0111 09:42:43.605777 31440 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.172601ms
I0111 09:42:43.605824 31440 replica.cpp:712] Persisted action at 0
I0111 09:42:43.607029 31435 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 09:42:43.639163 31435 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.044466ms
I0111 09:42:43.639202 31435 replica.cpp:712] Persisted action at 0
I0111 09:42:43.639225 31435 replica.cpp:697] Replica learned NOP action at position 0
I0111 09:42:43.639902 31447 log.cpp:675] Writer started with ending position 0
I0111 09:42:43.641067 31447 leveldb.cpp:436] Reading position from leveldb took 36642ns
I0111 09:42:43.642179 31445 registrar.cpp:340] Successfully fetched the registry (0B) in 178.844928ms
I0111 09:42:43.642304 31445 registrar.cpp:439] Applied 1 operations in 26231ns; attempting to update the 'registry'
I0111 09:42:43.643151 31447 log.cpp:683] Attempting to append 170 bytes to the log
I0111 09:42:43.643332 31445 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 09:42:43.644263 31446 replica.cpp:537] Replica received write request for position 1 from (13489)@172.17.0.3:46527
I0111 09:42:43.680938 31446 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 36.627348ms
I0111 09:42:43.680977 31446 replica.cpp:712] Persisted action at 1
I0111 09:42:43.681761 31437 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 09:42:43.722721 31437 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.921755ms
I0111 09:42:43.722764 31437 replica.cpp:712] Persisted action at 1
I0111 09:42:43.722800 31437 replica.cpp:697] Replica learned APPEND action at position 1
I0111 09:42:43.723893 31445 registrar.cpp:484] Successfully updated the 'registry' in 81.513728ms
I0111 09:42:43.724068 31445 registrar.cpp:370] Successfully recovered registrar
I0111 09:42:43.724179 31447 log.cpp:702] Attempting to truncate the log to 1
I0111 09:42:43.724524 31442 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 09:42:43.724776 31436 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 09:42:43.724813 31445 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 09:42:43.725566 31443 replica.cpp:537] Replica received write request for position 2 from (13490)@172.17.0.3:46527
I0111 09:42:43.764611 31443 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.99912ms
I0111 09:42:43.764657 31443 replica.cpp:712] Persisted action at 2
I0111 09:42:43.765569 31446 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 09:42:43.797950 31446 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.340629ms
I0111 09:42:43.798032 31446 leveldb.cpp:399] Deleting ~1 keys from leveldb took 41587ns
I0111 09:42:43.798058 31446 replica.cpp:712] Persisted action at 2
I0111 09:42:43.798080 31446 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 09:42:43.806859 31416 scheduler.cpp:154] Version: 0.27.0
I0111 09:42:43.807888 31445 scheduler.cpp:236] New master detected at master@172.17.0.3:46527
I0111 09:42:43.809310 31443 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:46527
I0111 09:42:43.811318 31439 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 09:42:43.811944 31442 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:39745
I0111 09:42:43.812361 31442 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 09:42:43.812427 31442 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 09:42:43.812711 31442 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 09:42:43.813235 31439 hierarchical.cpp:260] Added framework c81dca28-494d-45b0-891f-5bdf32a780d3-0000
I0111 09:42:43.813308 31439 hierarchical.cpp:1329] No resources available to allocate!
I0111 09:42:43.813467 31439 hierarchical.cpp:1423] No inverse offers to send out!
I0111 09:42:43.813504 31439 hierarchical.cpp:1079] Performed allocation for 0 slaves in 238722ns
I0111 09:42:43.813329 31437 master.hpp:1657] Sending heartbeat to c81dca28-494d-45b0-891f-5bdf32a780d3-0000
I0111 09:42:43.814321 31445 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:46527
I0111 09:42:43.814846 31437 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:46527
I0111 09:42:43.815596 31449 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 09:42:43.815634 31437 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:46527
I0111 09:42:43.817323 31443 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 09:42:43.817710 31437 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:39746
I0111 09:42:43.817978 31437 master.cpp:2722] Processing REQUEST call for framework c81dca28-494d-45b0-891f-5bdf32a780d3-0000 (default)
I0111 09:42:43.818168 31448 hierarchical.cpp:579] Received resource request from framework c81dca28-494d-45b0-891f-5bdf32a780d3-0000
I0111 09:42:43.818588 31444 master.cpp:1016] Master terminating
I0111 09:42:43.818853 31436 hierarchical.cpp:321] Removed framework c81dca28-494d-45b0-891f-5bdf32a780d3-0000
E0111 09:42:43.819741 31448 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (562 ms)
[----------] 22 tests from ContentType/SchedulerTest (15071 ms total)
[----------] Global test environment tear-down
[==========] 925 tests from 122 test cases ran. (760838 ms total)
[ PASSED ] 916 tests.
[ FAILED ] 9 tests, listed below:
[ FAILED ] FetcherCacheTest.LocalCached
[ FAILED ] FetcherCacheTest.CachedFallback
[ FAILED ] FetcherCacheTest.LocalCachedExtract
[ FAILED ] FetcherCacheTest.SimpleEviction
[ FAILED ] FetcherCacheTest.FallbackFromEviction
[ FAILED ] FetcherCacheTest.RemoveLRUCacheEntries
[ FAILED ] FetcherCacheHttpTest.HttpCachedSerialized
[ FAILED ] FetcherCacheHttpTest.HttpCachedConcurrent
[ FAILED ] FetcherCacheHttpTest.HttpMixed
9 FAILED TESTS
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1452502973-22268
Untagged: mesos-1452502973-22268:latest
Deleted: d884a23c786dfe03b0fcf5cc153d36ef81ddf8dcf7b2d7dd11b9572769b8fb3b
Deleted: 68b1133e49acd3de38aa48fea868b3ed7ff233a952e3cabe539a78fe3cb3f4d9
Deleted: 2446f400533509d21955d97e60961da658b827f42c877be9129e7c1ede4f89d4
Deleted: de92a2502dde18e4a828d2805ac9cbdeac1454619484757a3fe533dec7b1867d
Deleted: 5c359ecb2216b620fc7719228b6a124979997055ee42b236b3522e6aadcc6464
Deleted: 80860bf2dfa9651fba0861c3aa851f29e248e691eee98347b8529cf088e45184
Deleted: f2e4e31c98bdc16e1247619a93492336b0d7f00608bd4dbd1b7df2b3338415c8
Deleted: 7f9473c1cf4fedd89e035ba4ac1d682ea59110370b504ff54cd97271c02a5ef3
Deleted: f22ec0e0fac5432a2a157ccce29247c0a695181c9c55aad36412ca6fa95c7341
Deleted: ef3109179f5774f350a389f28fd9916b4c033b6e0e0f658b3ad9b32862bde03b
Deleted: 6c2f38468c10c4a60c8feec9458ed2e8f9811b2398616f47eade7ca778895982
Deleted: 26e3ccb73e1636f65b706d1d849e0868de4f9c06393137aae9860450de51b0df
Deleted: 287c07ec8d59a6d9dc3c17ec51a6a19b96817b350603c9ba7c76c94518320ab5
Deleted: 6170204fd22b209bb609a534bad527026ea5fbb2efa2f25d7ce5d48cb4fe36b5
Error handling URL https://reviews.apache.org/api/review-requests/41590/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 468b8ecbe5bcb968a4e802a0cf2fa95da2234af7
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10712
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10712/>
Build failed in Jenkins: mesos-reviewbot #10711
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10711/>
------------------------------------------
[...truncated 165385 lines...]
I0111 10:49:07.666821 31439 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 10:49:07.667464 31443 recover.cpp:564] Updating replica status to STARTING
I0111 10:49:07.668431 31446 master.cpp:372] Master aca8e8ed-c38c-4912-b2fe-43f94c68ef74 (fa9da77a25af) started on 172.17.0.2:35749
I0111 10:49:07.668457 31446 master.cpp:374] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/C7h2ot/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/C7h2ot/master" --zk_session_timeout="10secs"
I0111 10:49:07.668819 31446 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 10:49:07.668833 31446 master.cpp:424] Master only allowing authenticated slaves to register
I0111 10:49:07.668843 31446 credentials.hpp:35] Loading credentials for authentication from '/tmp/C7h2ot/credentials'
I0111 10:49:07.669183 31446 master.cpp:464] Using default 'crammd5' authenticator
I0111 10:49:07.669339 31446 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 10:49:07.669493 31446 master.cpp:567] Authorization enabled
I0111 10:49:07.669831 31447 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 10:49:07.669844 31438 whitelist_watcher.cpp:77] No whitelist given
I0111 10:49:07.672266 31437 master.cpp:1715] The newly elected leader is master@172.17.0.2:35749 with id aca8e8ed-c38c-4912-b2fe-43f94c68ef74
I0111 10:49:07.672302 31437 master.cpp:1728] Elected as the leading master!
I0111 10:49:07.672320 31437 master.cpp:1473] Recovering from registrar
I0111 10:49:07.672457 31439 registrar.cpp:307] Recovering registrar
I0111 10:49:07.688374 31450 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.682773ms
I0111 10:49:07.688410 31450 replica.cpp:320] Persisted replica status to STARTING
I0111 10:49:07.688673 31442 recover.cpp:473] Replica is in STARTING status
I0111 10:49:07.689759 31442 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13446)@172.17.0.2:35749
I0111 10:49:07.690062 31438 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 10:49:07.690822 31435 recover.cpp:564] Updating replica status to VOTING
I0111 10:49:07.713624 31442 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.972353ms
I0111 10:49:07.713662 31442 replica.cpp:320] Persisted replica status to VOTING
I0111 10:49:07.713824 31439 recover.cpp:578] Successfully joined the Paxos group
I0111 10:49:07.714030 31439 recover.cpp:462] Recover process terminated
I0111 10:49:07.714737 31446 log.cpp:659] Attempting to start the writer
I0111 10:49:07.716670 31449 replica.cpp:493] Replica received implicit promise request from (13447)@172.17.0.2:35749 with proposal 1
I0111 10:49:07.738569 31449 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.854542ms
I0111 10:49:07.738605 31449 replica.cpp:342] Persisted promised to 1
I0111 10:49:07.739543 31446 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 10:49:07.741082 31436 replica.cpp:388] Replica received explicit promise request from (13448)@172.17.0.2:35749 for position 0 with proposal 2
I0111 10:49:07.755475 31436 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 14.301751ms
I0111 10:49:07.755518 31436 replica.cpp:712] Persisted action at 0
I0111 10:49:07.756747 31449 replica.cpp:537] Replica received write request for position 0 from (13449)@172.17.0.2:35749
I0111 10:49:07.756815 31449 leveldb.cpp:436] Reading position from leveldb took 32380ns
I0111 10:49:07.772186 31449 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 15.324083ms
I0111 10:49:07.772224 31449 replica.cpp:712] Persisted action at 0
I0111 10:49:07.772979 31447 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 10:49:07.789052 31447 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.983203ms
I0111 10:49:07.789083 31447 replica.cpp:712] Persisted action at 0
I0111 10:49:07.789101 31447 replica.cpp:697] Replica learned NOP action at position 0
I0111 10:49:07.789813 31447 log.cpp:675] Writer started with ending position 0
I0111 10:49:07.791085 31447 leveldb.cpp:436] Reading position from leveldb took 37731ns
I0111 10:49:07.792259 31436 registrar.cpp:340] Successfully fetched the registry (0B) in 119.746048ms
I0111 10:49:07.792376 31436 registrar.cpp:439] Applied 1 operations in 26070ns; attempting to update the 'registry'
I0111 10:49:07.793241 31448 log.cpp:683] Attempting to append 170 bytes to the log
I0111 10:49:07.793390 31436 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 10:49:07.794399 31450 replica.cpp:537] Replica received write request for position 1 from (13450)@172.17.0.2:35749
I0111 10:49:07.814183 31450 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 19.737969ms
I0111 10:49:07.814223 31450 replica.cpp:712] Persisted action at 1
I0111 10:49:07.815009 31448 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 10:49:07.830893 31448 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 15.840464ms
I0111 10:49:07.830936 31448 replica.cpp:712] Persisted action at 1
I0111 10:49:07.830960 31448 replica.cpp:697] Replica learned APPEND action at position 1
I0111 10:49:07.832226 31441 registrar.cpp:484] Successfully updated the 'registry' in 39.77216ms
I0111 10:49:07.832437 31441 registrar.cpp:370] Successfully recovered registrar
I0111 10:49:07.832499 31449 log.cpp:702] Attempting to truncate the log to 1
I0111 10:49:07.832643 31447 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 10:49:07.833210 31442 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 10:49:07.833205 31444 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 10:49:07.833686 31437 replica.cpp:537] Replica received write request for position 2 from (13451)@172.17.0.2:35749
I0111 10:49:07.855973 31437 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.235013ms
I0111 10:49:07.856011 31437 replica.cpp:712] Persisted action at 2
I0111 10:49:07.856688 31440 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 10:49:07.896428 31440 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 39.608153ms
I0111 10:49:07.896524 31440 leveldb.cpp:399] Deleting ~1 keys from leveldb took 50711ns
I0111 10:49:07.896555 31440 replica.cpp:712] Persisted action at 2
I0111 10:49:07.896587 31440 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 10:49:07.904856 31416 scheduler.cpp:154] Version: 0.27.0
I0111 10:49:07.905532 31448 scheduler.cpp:236] New master detected at master@172.17.0.2:35749
I0111 10:49:07.906787 31436 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:35749
I0111 10:49:07.908805 31440 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 10:49:07.909531 31437 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43843
I0111 10:49:07.909767 31437 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 10:49:07.909833 31437 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 10:49:07.910178 31437 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 10:49:07.910624 31435 hierarchical.cpp:260] Added framework aca8e8ed-c38c-4912-b2fe-43f94c68ef74-0000
I0111 10:49:07.910688 31436 master.hpp:1657] Sending heartbeat to aca8e8ed-c38c-4912-b2fe-43f94c68ef74-0000
I0111 10:49:07.910691 31435 hierarchical.cpp:1329] No resources available to allocate!
I0111 10:49:07.910771 31435 hierarchical.cpp:1423] No inverse offers to send out!
I0111 10:49:07.910809 31435 hierarchical.cpp:1079] Performed allocation for 0 slaves in 153331ns
I0111 10:49:07.911788 31448 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:35749
I0111 10:49:07.912372 31448 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:35749
I0111 10:49:07.913053 31442 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 10:49:07.913177 31448 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:35749
I0111 10:49:07.915000 31446 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 10:49:07.915491 31437 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43844
I0111 10:49:07.915627 31437 master.cpp:2722] Processing REQUEST call for framework aca8e8ed-c38c-4912-b2fe-43f94c68ef74-0000 (default)
I0111 10:49:07.915830 31449 hierarchical.cpp:579] Received resource request from framework aca8e8ed-c38c-4912-b2fe-43f94c68ef74-0000
I0111 10:49:07.916226 31435 master.cpp:1016] Master terminating
I0111 10:49:07.916545 31440 hierarchical.cpp:321] Removed framework aca8e8ed-c38c-4912-b2fe-43f94c68ef74-0000
E0111 10:49:07.917891 31442 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (410 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0111 10:49:08.049923 31416 leveldb.cpp:174] Opened db in 125.27949ms
I0111 10:49:08.108589 31416 leveldb.cpp:181] Compacted db in 58.622807ms
I0111 10:49:08.108651 31416 leveldb.cpp:196] Created db iterator in 16932ns
I0111 10:49:08.108671 31416 leveldb.cpp:202] Seeked to beginning of db in 1782ns
I0111 10:49:08.108682 31416 leveldb.cpp:271] Iterated through 0 keys in the db in 220ns
I0111 10:49:08.108719 31416 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0111 10:49:08.109212 31443 recover.cpp:447] Starting replica recovery
I0111 10:49:08.109488 31443 recover.cpp:473] Replica is in EMPTY status
I0111 10:49:08.110299 31449 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13459)@172.17.0.2:35749
I0111 10:49:08.110662 31437 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 10:49:08.111101 31447 recover.cpp:564] Updating replica status to STARTING
I0111 10:49:08.111821 31442 master.cpp:372] Master f7bd981b-7d66-4f39-952a-252ee0499d0c (fa9da77a25af) started on 172.17.0.2:35749
I0111 10:49:08.111855 31442 master.cpp:374] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/OE9EPa/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/OE9EPa/master" --zk_session_timeout="10secs"
I0111 10:49:08.112090 31442 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 10:49:08.112099 31442 master.cpp:424] Master only allowing authenticated slaves to register
I0111 10:49:08.112108 31442 credentials.hpp:35] Loading credentials for authentication from '/tmp/OE9EPa/credentials'
I0111 10:49:08.112375 31442 master.cpp:464] Using default 'crammd5' authenticator
I0111 10:49:08.112491 31442 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 10:49:08.112622 31442 master.cpp:567] Authorization enabled
I0111 10:49:08.112771 31437 whitelist_watcher.cpp:77] No whitelist given
I0111 10:49:08.112777 31445 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 10:49:08.114220 31441 master.cpp:1715] The newly elected leader is master@172.17.0.2:35749 with id f7bd981b-7d66-4f39-952a-252ee0499d0c
I0111 10:49:08.114261 31441 master.cpp:1728] Elected as the leading master!
I0111 10:49:08.114285 31441 master.cpp:1473] Recovering from registrar
I0111 10:49:08.114465 31449 registrar.cpp:307] Recovering registrar
I0111 10:49:08.148077 31450 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 36.821349ms
I0111 10:49:08.148123 31450 replica.cpp:320] Persisted replica status to STARTING
I0111 10:49:08.148427 31450 recover.cpp:473] Replica is in STARTING status
I0111 10:49:08.149492 31445 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13461)@172.17.0.2:35749
I0111 10:49:08.149870 31448 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 10:49:08.150372 31437 recover.cpp:564] Updating replica status to VOTING
I0111 10:49:08.181426 31445 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.839535ms
I0111 10:49:08.181463 31445 replica.cpp:320] Persisted replica status to VOTING
I0111 10:49:08.181597 31449 recover.cpp:578] Successfully joined the Paxos group
I0111 10:49:08.181848 31449 recover.cpp:462] Recover process terminated
I0111 10:49:08.182318 31450 log.cpp:659] Attempting to start the writer
I0111 10:49:08.183543 31448 replica.cpp:493] Replica received implicit promise request from (13462)@172.17.0.2:35749 with proposal 1
I0111 10:49:08.348390 31448 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 164.810884ms
I0111 10:49:08.348435 31448 replica.cpp:342] Persisted promised to 1
I0111 10:49:08.349248 31449 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 10:49:08.350311 31443 replica.cpp:388] Replica received explicit promise request from (13463)@172.17.0.2:35749 for position 0 with proposal 2
I0111 10:49:08.373395 31443 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 23.041072ms
I0111 10:49:08.373435 31443 replica.cpp:712] Persisted action at 0
I0111 10:49:08.374423 31444 replica.cpp:537] Replica received write request for position 0 from (13464)@172.17.0.2:35749
I0111 10:49:08.374481 31444 leveldb.cpp:436] Reading position from leveldb took 24588ns
I0111 10:49:08.398489 31444 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.970241ms
I0111 10:49:08.398519 31444 replica.cpp:712] Persisted action at 0
I0111 10:49:08.399015 31441 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 10:49:08.423665 31441 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.617423ms
I0111 10:49:08.423692 31441 replica.cpp:712] Persisted action at 0
I0111 10:49:08.423708 31441 replica.cpp:697] Replica learned NOP action at position 0
I0111 10:49:08.424319 31448 log.cpp:675] Writer started with ending position 0
I0111 10:49:08.425437 31436 leveldb.cpp:436] Reading position from leveldb took 32917ns
I0111 10:49:08.426491 31445 registrar.cpp:340] Successfully fetched the registry (0B) in 311.906048ms
I0111 10:49:08.426604 31445 registrar.cpp:439] Applied 1 operations in 24294ns; attempting to update the 'registry'
I0111 10:49:08.427465 31436 log.cpp:683] Attempting to append 170 bytes to the log
I0111 10:49:08.427603 31447 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 10:49:08.428470 31447 replica.cpp:537] Replica received write request for position 1 from (13465)@172.17.0.2:35749
I0111 10:49:08.448750 31447 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 20.236365ms
I0111 10:49:08.448791 31447 replica.cpp:712] Persisted action at 1
I0111 10:49:08.449641 31438 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 10:49:08.474408 31438 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.729783ms
I0111 10:49:08.474447 31438 replica.cpp:712] Persisted action at 1
I0111 10:49:08.474468 31438 replica.cpp:697] Replica learned APPEND action at position 1
I0111 10:49:08.475702 31446 registrar.cpp:484] Successfully updated the 'registry' in 49.003264ms
I0111 10:49:08.475869 31446 registrar.cpp:370] Successfully recovered registrar
I0111 10:49:08.475975 31436 log.cpp:702] Attempting to truncate the log to 1
I0111 10:49:08.476196 31450 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 10:49:08.476553 31440 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 10:49:08.476584 31448 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 10:49:08.477330 31438 replica.cpp:537] Replica received write request for position 2 from (13466)@172.17.0.2:35749
I0111 10:49:08.499449 31438 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.071375ms
I0111 10:49:08.499488 31438 replica.cpp:712] Persisted action at 2
I0111 10:49:08.500305 31448 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 10:49:08.524585 31448 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.238449ms
I0111 10:49:08.524673 31448 leveldb.cpp:399] Deleting ~1 keys from leveldb took 47759ns
I0111 10:49:08.524703 31448 replica.cpp:712] Persisted action at 2
I0111 10:49:08.524735 31448 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 10:49:08.527413 31416 scheduler.cpp:154] Version: 0.27.0
I0111 10:49:08.528054 31447 scheduler.cpp:236] New master detected at master@172.17.0.2:35749
I0111 10:49:08.529394 31448 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:35749
I0111 10:49:08.531590 31439 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 10:49:08.532203 31435 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43845
I0111 10:49:08.532599 31435 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 10:49:08.532675 31435 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 10:49:08.533007 31435 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 10:49:08.533517 31436 hierarchical.cpp:260] Added framework f7bd981b-7d66-4f39-952a-252ee0499d0c-0000
I0111 10:49:08.533591 31436 hierarchical.cpp:1329] No resources available to allocate!
I0111 10:49:08.533653 31436 hierarchical.cpp:1423] No inverse offers to send out!
I0111 10:49:08.533686 31436 hierarchical.cpp:1079] Performed allocation for 0 slaves in 134914ns
I0111 10:49:08.533792 31445 master.hpp:1657] Sending heartbeat to f7bd981b-7d66-4f39-952a-252ee0499d0c-0000
I0111 10:49:08.534708 31437 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:35749
I0111 10:49:08.535830 31442 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:35749
I0111 10:49:08.536208 31442 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:35749
I0111 10:49:08.536993 31438 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 10:49:08.538034 31441 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 10:49:08.538509 31450 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:43846
I0111 10:49:08.538758 31450 master.cpp:2722] Processing REQUEST call for framework f7bd981b-7d66-4f39-952a-252ee0499d0c-0000 (default)
I0111 10:49:08.538921 31440 hierarchical.cpp:579] Received resource request from framework f7bd981b-7d66-4f39-952a-252ee0499d0c-0000
I0111 10:49:08.539324 31446 master.cpp:1016] Master terminating
I0111 10:49:08.539551 31441 hierarchical.cpp:321] Removed framework f7bd981b-7d66-4f39-952a-252ee0499d0c-0000
E0111 10:49:08.540799 31437 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (622 ms)
[----------] 22 tests from ContentType/SchedulerTest (15099 ms total)
[----------] Global test environment tear-down
2016-01-11 10:49:08,621:31416(0x2af877d80700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:59039] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 925 tests from 122 test cases ran. (767108 ms total)
[ PASSED ] 916 tests.
[ FAILED ] 9 tests, listed below:
[ FAILED ] FetcherCacheTest.LocalCached
[ FAILED ] FetcherCacheTest.CachedFallback
[ FAILED ] FetcherCacheTest.LocalCachedExtract
[ FAILED ] FetcherCacheTest.SimpleEviction
[ FAILED ] FetcherCacheTest.FallbackFromEviction
[ FAILED ] FetcherCacheTest.RemoveLRUCacheEntries
[ FAILED ] FetcherCacheHttpTest.HttpCachedSerialized
[ FAILED ] FetcherCacheHttpTest.HttpCachedConcurrent
[ FAILED ] FetcherCacheHttpTest.HttpMixed
9 FAILED TESTS
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1452507001-12850
Untagged: mesos-1452507001-12850:latest
Deleted: bc3ef9835be27c9ea1a0bf3b03ba669df0d723ed8d7eb9c02ed8bfbdff187a03
Deleted: 2f64dd346791793b54a94c5286f5b80ba58ce39506d0a45800d340cc46900fec
Deleted: e949cb9ec5c090a355004eb74eb44b1e17d83209d401a79045485ab4654f3d1a
Deleted: 142be69dc8833338acb4459e4f696a92f55c365f5977f3ecf6909fb095df0890
Deleted: 9e8181ca68c8d972c91619248838f6aee20bb7e8b023c142c0d5434495620e9c
Deleted: f44b851900fade45e408bc34a321340f69c5c180808f8c89914d11f5bbcb6a55
Deleted: a9813b484a3fb3369251a641458180e5ea482f8bd9e1f5b34212999790afab95
Deleted: f2c2df62493966ab4c23ac889a27d426372a678167d8ab8c009639c9bbc53c9e
Deleted: 748ec59aad88f370db8a20931fd65158260ab45b3382364c9e75825c2e77153e
Deleted: 4f4a2c99e89b851a25397835f9e1b74685936b35fb42f95e529c8ea6cd390874
Deleted: 88362cc97e1a7f57e6439496859b17bb6a2862b4f4821150cb412c88cbec72fe
Deleted: 170535ef26a1321871335ff04e68f55b56affcdc92072b1365ac82904d143608
Deleted: 892cbdc3c8fea10f55bc137ca6da3ba7052311ed780e002b80fc49f519e5bb57
Deleted: 3cf0e6b9a9e03f015dbeb816efa15966d85c9f05500e949566957389f7b6f81b
Error handling URL https://reviews.apache.org/api/review-requests/41590/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 468b8ecbe5bcb968a4e802a0cf2fa95da2234af7
Build step 'Execute shell' marked build as failure