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 09:33:06 UTC
Build failed in Jenkins: mesos-reviewbot #10709
See <https://builds.apache.org/job/mesos-reviewbot/10709/>
------------------------------------------
[...truncated 165280 lines...]
I0111 08:32:53.943536 31426 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 08:32:53.944011 31427 recover.cpp:564] Updating replica status to STARTING
I0111 08:32:53.944790 31437 master.cpp:372] Master f4e0fc75-2dbf-4833-baab-c495ebe3b76b (c2811f3e5b29) started on 172.17.0.3:48873
I0111 08:32:53.944823 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/xYkBLb/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/xYkBLb/master" --zk_session_timeout="10secs"
I0111 08:32:53.945193 31437 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 08:32:53.945214 31437 master.cpp:424] Master only allowing authenticated slaves to register
I0111 08:32:53.945224 31437 credentials.hpp:35] Loading credentials for authentication from '/tmp/xYkBLb/credentials'
I0111 08:32:53.945535 31437 master.cpp:464] Using default 'crammd5' authenticator
I0111 08:32:53.945688 31437 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 08:32:53.945859 31437 master.cpp:567] Authorization enabled
I0111 08:32:53.946054 31439 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 08:32:53.946074 31427 whitelist_watcher.cpp:77] No whitelist given
I0111 08:32:53.947974 31432 master.cpp:1715] The newly elected leader is master@172.17.0.3:48873 with id f4e0fc75-2dbf-4833-baab-c495ebe3b76b
I0111 08:32:53.948017 31432 master.cpp:1728] Elected as the leading master!
I0111 08:32:53.948038 31432 master.cpp:1473] Recovering from registrar
I0111 08:32:53.948196 31438 registrar.cpp:307] Recovering registrar
I0111 08:32:53.982893 31440 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.679808ms
I0111 08:32:53.982931 31440 replica.cpp:320] Persisted replica status to STARTING
I0111 08:32:53.983229 31440 recover.cpp:473] Replica is in STARTING status
I0111 08:32:53.984210 31440 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13440)@172.17.0.3:48873
I0111 08:32:53.984665 31431 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 08:32:53.985191 31434 recover.cpp:564] Updating replica status to VOTING
2016-01-11 08:32:53,992:31407(0x2b68bb2ff700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:57842] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0111 08:32:54.017909 31427 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 32.504019ms
I0111 08:32:54.017951 31427 replica.cpp:320] Persisted replica status to VOTING
I0111 08:32:54.018079 31427 recover.cpp:578] Successfully joined the Paxos group
I0111 08:32:54.018357 31427 recover.cpp:462] Recover process terminated
I0111 08:32:54.018904 31428 log.cpp:659] Attempting to start the writer
I0111 08:32:54.020063 31435 replica.cpp:493] Replica received implicit promise request from (13441)@172.17.0.3:48873 with proposal 1
I0111 08:32:54.051194 31435 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.097461ms
I0111 08:32:54.051231 31435 replica.cpp:342] Persisted promised to 1
I0111 08:32:54.051834 31432 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 08:32:54.052997 31426 replica.cpp:388] Replica received explicit promise request from (13442)@172.17.0.3:48873 for position 0 with proposal 2
I0111 08:32:54.084787 31426 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.746366ms
I0111 08:32:54.084833 31426 replica.cpp:712] Persisted action at 0
I0111 08:32:54.086202 31437 replica.cpp:537] Replica received write request for position 0 from (13443)@172.17.0.3:48873
I0111 08:32:54.086271 31437 leveldb.cpp:436] Reading position from leveldb took 29711ns
I0111 08:32:54.118294 31437 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.972282ms
I0111 08:32:54.118333 31437 replica.cpp:712] Persisted action at 0
I0111 08:32:54.119390 31437 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 08:32:54.151698 31437 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.267728ms
I0111 08:32:54.151736 31437 replica.cpp:712] Persisted action at 0
I0111 08:32:54.151757 31437 replica.cpp:697] Replica learned NOP action at position 0
I0111 08:32:54.152469 31438 log.cpp:675] Writer started with ending position 0
I0111 08:32:54.153676 31434 leveldb.cpp:436] Reading position from leveldb took 30355ns
I0111 08:32:54.154700 31439 registrar.cpp:340] Successfully fetched the registry (0B) in 206.388224ms
I0111 08:32:54.154847 31439 registrar.cpp:439] Applied 1 operations in 37124ns; attempting to update the 'registry'
I0111 08:32:54.155688 31433 log.cpp:683] Attempting to append 170 bytes to the log
I0111 08:32:54.155895 31436 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 08:32:54.156680 31433 replica.cpp:537] Replica received write request for position 1 from (13444)@172.17.0.3:48873
I0111 08:32:54.193387 31433 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 36.663189ms
I0111 08:32:54.193426 31433 replica.cpp:712] Persisted action at 1
I0111 08:32:54.194242 31432 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 08:32:54.226927 31432 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.644614ms
I0111 08:32:54.226975 31432 replica.cpp:712] Persisted action at 1
I0111 08:32:54.227010 31432 replica.cpp:697] Replica learned APPEND action at position 1
I0111 08:32:54.228194 31429 registrar.cpp:484] Successfully updated the 'registry' in 73.274112ms
I0111 08:32:54.228377 31429 registrar.cpp:370] Successfully recovered registrar
I0111 08:32:54.228492 31436 log.cpp:702] Attempting to truncate the log to 1
I0111 08:32:54.228636 31433 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 08:32:54.228958 31428 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 08:32:54.229007 31441 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 08:32:54.229607 31432 replica.cpp:537] Replica received write request for position 2 from (13445)@172.17.0.3:48873
I0111 08:32:54.268561 31432 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.919913ms
I0111 08:32:54.268591 31432 replica.cpp:712] Persisted action at 2
I0111 08:32:54.269218 31430 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 08:32:54.302001 31430 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.753554ms
I0111 08:32:54.302065 31430 leveldb.cpp:399] Deleting ~1 keys from leveldb took 32574ns
I0111 08:32:54.302093 31430 replica.cpp:712] Persisted action at 2
I0111 08:32:54.302110 31430 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 08:32:54.309980 31407 scheduler.cpp:154] Version: 0.27.0
I0111 08:32:54.310827 31440 scheduler.cpp:236] New master detected at master@172.17.0.3:48873
I0111 08:32:54.312022 31432 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:48873
I0111 08:32:54.313885 31436 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 08:32:54.314513 31433 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:35251
I0111 08:32:54.314743 31433 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 08:32:54.314823 31433 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 08:32:54.315171 31433 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 08:32:54.315636 31439 hierarchical.cpp:260] Added framework f4e0fc75-2dbf-4833-baab-c495ebe3b76b-0000
I0111 08:32:54.315651 31428 master.hpp:1657] Sending heartbeat to f4e0fc75-2dbf-4833-baab-c495ebe3b76b-0000
I0111 08:32:54.315809 31439 hierarchical.cpp:1329] No resources available to allocate!
I0111 08:32:54.315868 31439 hierarchical.cpp:1423] No inverse offers to send out!
I0111 08:32:54.316215 31439 hierarchical.cpp:1079] Performed allocation for 0 slaves in 457237ns
I0111 08:32:54.316668 31440 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:48873
I0111 08:32:54.317299 31440 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:48873
I0111 08:32:54.318156 31430 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 08:32:54.318265 31440 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:48873
I0111 08:32:54.320034 31437 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 08:32:54.320562 31435 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:35252
I0111 08:32:54.320713 31435 master.cpp:2722] Processing REQUEST call for framework f4e0fc75-2dbf-4833-baab-c495ebe3b76b-0000 (default)
I0111 08:32:54.320888 31432 hierarchical.cpp:579] Received resource request from framework f4e0fc75-2dbf-4833-baab-c495ebe3b76b-0000
I0111 08:32:54.321319 31432 master.cpp:1016] Master terminating
I0111 08:32:54.321595 31431 hierarchical.cpp:321] Removed framework f4e0fc75-2dbf-4833-baab-c495ebe3b76b-0000
E0111 08:32:54.323034 31435 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (547 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0111 08:32:54.404077 31407 leveldb.cpp:174] Opened db in 74.83827ms
I0111 08:32:54.421267 31407 leveldb.cpp:181] Compacted db in 17.14796ms
I0111 08:32:54.421329 31407 leveldb.cpp:196] Created db iterator in 17319ns
I0111 08:32:54.421347 31407 leveldb.cpp:202] Seeked to beginning of db in 1802ns
I0111 08:32:54.421358 31407 leveldb.cpp:271] Iterated through 0 keys in the db in 325ns
I0111 08:32:54.421393 31407 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0111 08:32:54.421949 31439 recover.cpp:447] Starting replica recovery
I0111 08:32:54.422430 31439 recover.cpp:473] Replica is in EMPTY status
I0111 08:32:54.423451 31439 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (13453)@172.17.0.3:48873
I0111 08:32:54.423693 31431 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0111 08:32:54.424253 31438 recover.cpp:564] Updating replica status to STARTING
I0111 08:32:54.425377 31438 master.cpp:372] Master d4bda3ec-1c96-4ba8-9077-8421ce6cc57f (c2811f3e5b29) started on 172.17.0.3:48873
I0111 08:32:54.425412 31438 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/cJ7lbw/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/cJ7lbw/master" --zk_session_timeout="10secs"
I0111 08:32:54.425731 31438 master.cpp:421] Master allowing unauthenticated frameworks to register
I0111 08:32:54.425753 31438 master.cpp:424] Master only allowing authenticated slaves to register
I0111 08:32:54.425770 31438 credentials.hpp:35] Loading credentials for authentication from '/tmp/cJ7lbw/credentials'
I0111 08:32:54.426102 31438 master.cpp:464] Using default 'crammd5' authenticator
I0111 08:32:54.426283 31438 master.cpp:533] Using default 'basic' HTTP authenticator
I0111 08:32:54.426461 31438 master.cpp:567] Authorization enabled
I0111 08:32:54.426653 31433 whitelist_watcher.cpp:77] No whitelist given
I0111 08:32:54.426662 31427 hierarchical.cpp:147] Initialized hierarchical allocator process
I0111 08:32:54.428457 31428 master.cpp:1715] The newly elected leader is master@172.17.0.3:48873 with id d4bda3ec-1c96-4ba8-9077-8421ce6cc57f
I0111 08:32:54.428571 31428 master.cpp:1728] Elected as the leading master!
I0111 08:32:54.428616 31428 master.cpp:1473] Recovering from registrar
I0111 08:32:54.428771 31433 registrar.cpp:307] Recovering registrar
I0111 08:32:54.452229 31432 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.801018ms
I0111 08:32:54.452260 31432 replica.cpp:320] Persisted replica status to STARTING
I0111 08:32:54.452467 31439 recover.cpp:473] Replica is in STARTING status
I0111 08:32:54.453464 31439 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (13455)@172.17.0.3:48873
I0111 08:32:54.453894 31427 recover.cpp:193] Received a recover response from a replica in STARTING status
I0111 08:32:54.454452 31432 recover.cpp:564] Updating replica status to VOTING
I0111 08:32:54.485805 31426 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.980849ms
I0111 08:32:54.485848 31426 replica.cpp:320] Persisted replica status to VOTING
I0111 08:32:54.485977 31434 recover.cpp:578] Successfully joined the Paxos group
I0111 08:32:54.486249 31434 recover.cpp:462] Recover process terminated
I0111 08:32:54.486883 31426 log.cpp:659] Attempting to start the writer
I0111 08:32:54.488211 31433 replica.cpp:493] Replica received implicit promise request from (13456)@172.17.0.3:48873 with proposal 1
I0111 08:32:54.519296 31433 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.046657ms
I0111 08:32:54.519341 31433 replica.cpp:342] Persisted promised to 1
I0111 08:32:54.520201 31426 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0111 08:32:54.521631 31434 replica.cpp:388] Replica received explicit promise request from (13457)@172.17.0.3:48873 for position 0 with proposal 2
I0111 08:32:54.552637 31434 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.951789ms
I0111 08:32:54.552681 31434 replica.cpp:712] Persisted action at 0
I0111 08:32:54.553885 31427 replica.cpp:537] Replica received write request for position 0 from (13458)@172.17.0.3:48873
I0111 08:32:54.553972 31427 leveldb.cpp:436] Reading position from leveldb took 38824ns
I0111 08:32:54.586156 31427 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 32.12578ms
I0111 08:32:54.586195 31427 replica.cpp:712] Persisted action at 0
I0111 08:32:54.587034 31438 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0111 08:32:54.619498 31438 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.421039ms
I0111 08:32:54.619537 31438 replica.cpp:712] Persisted action at 0
I0111 08:32:54.619560 31438 replica.cpp:697] Replica learned NOP action at position 0
I0111 08:32:54.620252 31426 log.cpp:675] Writer started with ending position 0
I0111 08:32:54.621160 31428 leveldb.cpp:436] Reading position from leveldb took 39374ns
I0111 08:32:54.621876 31431 registrar.cpp:340] Successfully fetched the registry (0B) in 193.054976ms
I0111 08:32:54.621975 31431 registrar.cpp:439] Applied 1 operations in 21341ns; attempting to update the 'registry'
I0111 08:32:54.622642 31427 log.cpp:683] Attempting to append 170 bytes to the log
I0111 08:32:54.622787 31438 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0111 08:32:54.623427 31437 replica.cpp:537] Replica received write request for position 1 from (13459)@172.17.0.3:48873
I0111 08:32:54.661303 31437 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 37.83788ms
I0111 08:32:54.661344 31437 replica.cpp:712] Persisted action at 1
I0111 08:32:54.662083 31435 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0111 08:32:54.694859 31435 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.741777ms
I0111 08:32:54.694897 31435 replica.cpp:712] Persisted action at 1
I0111 08:32:54.694919 31435 replica.cpp:697] Replica learned APPEND action at position 1
I0111 08:32:54.696025 31438 registrar.cpp:484] Successfully updated the 'registry' in 73.982976ms
I0111 08:32:54.696252 31438 registrar.cpp:370] Successfully recovered registrar
I0111 08:32:54.696359 31441 log.cpp:702] Attempting to truncate the log to 1
I0111 08:32:54.696517 31427 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0111 08:32:54.696846 31430 master.cpp:1525] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0111 08:32:54.696876 31436 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I0111 08:32:54.697589 31427 replica.cpp:537] Replica received write request for position 2 from (13460)@172.17.0.3:48873
I0111 08:32:54.736505 31427 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.868309ms
I0111 08:32:54.736543 31427 replica.cpp:712] Persisted action at 2
I0111 08:32:54.737362 31438 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0111 08:32:54.770021 31438 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.620896ms
I0111 08:32:54.770107 31438 leveldb.cpp:399] Deleting ~1 keys from leveldb took 43939ns
I0111 08:32:54.770164 31438 replica.cpp:712] Persisted action at 2
I0111 08:32:54.770195 31438 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0111 08:32:54.777984 31407 scheduler.cpp:154] Version: 0.27.0
I0111 08:32:54.778630 31430 scheduler.cpp:236] New master detected at master@172.17.0.3:48873
I0111 08:32:54.779806 31438 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:48873
I0111 08:32:54.781798 31431 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 08:32:54.782313 31435 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:35253
I0111 08:32:54.782709 31435 master.cpp:1977] Received subscription request for HTTP framework 'default'
I0111 08:32:54.782774 31435 master.cpp:1754] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0111 08:32:54.783074 31435 master.cpp:2068] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0111 08:32:54.783561 31440 hierarchical.cpp:260] Added framework d4bda3ec-1c96-4ba8-9077-8421ce6cc57f-0000
I0111 08:32:54.783633 31440 hierarchical.cpp:1329] No resources available to allocate!
I0111 08:32:54.783679 31440 hierarchical.cpp:1423] No inverse offers to send out!
I0111 08:32:54.783716 31440 hierarchical.cpp:1079] Performed allocation for 0 slaves in 120712ns
I0111 08:32:54.783738 31435 master.hpp:1657] Sending heartbeat to d4bda3ec-1c96-4ba8-9077-8421ce6cc57f-0000
I0111 08:32:54.784809 31441 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:48873
I0111 08:32:54.785390 31441 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:48873
I0111 08:32:54.786320 31439 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0111 08:32:54.786357 31441 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:48873
I0111 08:32:54.788394 31427 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0111 08:32:54.788810 31433 http.cpp:315] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:35254
I0111 08:32:54.789057 31433 master.cpp:2722] Processing REQUEST call for framework d4bda3ec-1c96-4ba8-9077-8421ce6cc57f-0000 (default)
I0111 08:32:54.789265 31430 hierarchical.cpp:579] Received resource request from framework d4bda3ec-1c96-4ba8-9077-8421ce6cc57f-0000
I0111 08:32:54.790201 31434 master.cpp:1016] Master terminating
I0111 08:32:54.790500 31436 hierarchical.cpp:321] Removed framework d4bda3ec-1c96-4ba8-9077-8421ce6cc57f-0000
E0111 08:32:54.791779 31427 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (469 ms)
[----------] 22 tests from ContentType/SchedulerTest (15230 ms total)
[----------] Global test environment tear-down
[==========] 925 tests from 122 test cases ran. (781220 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-1452498646-28607
Untagged: mesos-1452498646-28607:latest
Deleted: cfedd0f1dfa8d418e5188fcab72813c77a8bff17c2f59a88c98fa7c4b3452b09
Deleted: 92ee82c1ce9877abc085403edb16768d0d077b14a054a5478ac8ea8ede375fde
Deleted: f4d354b5d67eac28b6a35b32829a2cf84913d08dc78f70e8a373d37b59e63c50
Deleted: e1cdc7dcfb7332c463846318d6d322c0f7a9d59958803c6de4510282c6165235
Deleted: f48af4f62f042db02be028943e608cd42bbd91ac5d52a36a5d582984c5537b3a
Deleted: de7dd6ceb819b030e6ca1563ca1fa60469bd2d16f5aa7ed6de22a393f7181a25
Deleted: 16dc411509a18309bfff83018118e1cd99cdd33f0b7f047e01ca8db8c29f114a
Deleted: 32061e56180cc20c84edd9c012e9ac27939ff38feafae74f6d2c6e1efc0e2f2c
Deleted: aa750b1ace8587a758674c35b551b014df5985c5958870094aa13b0c7db06d02
Deleted: a7504e8d520229f673350c7b592e7d2f15bbfa050769663c439f749a37d3244d
Deleted: 217e48734c6f4a8be3986ce23b263e20c232e0bfa6c00db84138779576e46c40
Deleted: d79a10fc323ade45971049906742c17644da66de030cbba22d84ae8e51107cb1
Deleted: 8fb8df5a69007b62298a9be640697340d724406a84a8536a5b74740666d1505b
Deleted: 9e3954fc6e235ce9f4ae48281c25f3c187f5fe3c148653a78c7bd7b80f96f20d
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
Build failed in Jenkins: mesos-reviewbot #10710
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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