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/04/24 15:41:39 UTC
Build failed in Jenkins: mesos-reviewbot #12700
See <https://builds.apache.org/job/mesos-reviewbot/12700/>
------------------------------------------
[...truncated 186986 lines...]
I0424 13:41:39.206879 32252 master.cpp:439] Master only allowing authenticated agents to register
I0424 13:41:39.206887 32252 master.cpp:445] Master only allowing authenticated HTTP frameworks to register
I0424 13:41:39.206897 32252 credentials.hpp:37] Loading credentials for authentication from '/tmp/n4gqqd/credentials'
I0424 13:41:39.207303 32252 master.cpp:489] Using default 'crammd5' authenticator
I0424 13:41:39.207468 32252 master.cpp:560] Using default 'basic' HTTP authenticator
I0424 13:41:39.207633 32252 master.cpp:640] Using default 'basic' HTTP framework authenticator
I0424 13:41:39.207738 32252 master.cpp:687] Authorization enabled
I0424 13:41:39.210999 32252 hierarchical.cpp:142] Initialized hierarchical allocator process
I0424 13:41:39.211122 32252 whitelist_watcher.cpp:77] No whitelist given
I0424 13:41:39.211964 32260 master.cpp:1932] The newly elected leader is master@172.17.0.1:57163 with id 648db41b-0e4e-400e-bad5-989c5ba39de9
I0424 13:41:39.212005 32260 master.cpp:1945] Elected as the leading master!
I0424 13:41:39.212033 32260 master.cpp:1632] Recovering from registrar
I0424 13:41:39.212204 32250 registrar.cpp:331] Recovering registrar
I0424 13:41:39.232913 32254 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 28.927709ms
I0424 13:41:39.233012 32254 replica.cpp:320] Persisted replica status to STARTING
I0424 13:41:39.233371 32260 recover.cpp:473] Replica is in STARTING status
I0424 13:41:39.234675 32245 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16909)@172.17.0.1:57163
I0424 13:41:39.235157 32246 recover.cpp:193] Received a recover response from a replica in STARTING status
I0424 13:41:39.235671 32245 recover.cpp:564] Updating replica status to VOTING
I0424 13:41:39.267398 32248 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.448941ms
I0424 13:41:39.267496 32248 replica.cpp:320] Persisted replica status to VOTING
I0424 13:41:39.267828 32252 recover.cpp:578] Successfully joined the Paxos group
I0424 13:41:39.268091 32252 recover.cpp:462] Recover process terminated
I0424 13:41:39.268790 32245 log.cpp:524] Attempting to start the writer
I0424 13:41:39.270177 32250 replica.cpp:493] Replica received implicit promise request from (16910)@172.17.0.1:57163 with proposal 1
I0424 13:41:39.292527 32250 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.28098ms
I0424 13:41:39.292639 32250 replica.cpp:342] Persisted promised to 1
I0424 13:41:39.293730 32254 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0424 13:41:39.295198 32246 replica.cpp:388] Replica received explicit promise request from (16911)@172.17.0.1:57163 for position 0 with proposal 2
I0424 13:41:39.317649 32246 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.37443ms
I0424 13:41:39.317739 32246 replica.cpp:712] Persisted action at 0
I0424 13:41:39.319254 32260 replica.cpp:537] Replica received write request for position 0 from (16912)@172.17.0.1:57163
I0424 13:41:39.319350 32260 leveldb.cpp:436] Reading position from leveldb took 49741ns
I0424 13:41:39.342921 32260 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.498462ms
I0424 13:41:39.343021 32260 replica.cpp:712] Persisted action at 0
I0424 13:41:39.344152 32259 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0424 13:41:39.368062 32259 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.846132ms
I0424 13:41:39.368158 32259 replica.cpp:712] Persisted action at 0
I0424 13:41:39.368198 32259 replica.cpp:697] Replica learned NOP action at position 0
I0424 13:41:39.369051 32250 log.cpp:540] Writer started with ending position 0
I0424 13:41:39.370517 32257 leveldb.cpp:436] Reading position from leveldb took 96957ns
I0424 13:41:39.371831 32247 registrar.cpp:364] Successfully fetched the registry (0B) in 159.566848ms
I0424 13:41:39.371991 32247 registrar.cpp:463] Applied 1 operations in 45573ns; attempting to update the 'registry'
I0424 13:41:39.373076 32254 log.cpp:548] Attempting to append 170 bytes to the log
I0424 13:41:39.373361 32254 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0424 13:41:39.374631 32254 replica.cpp:537] Replica received write request for position 1 from (16913)@172.17.0.1:57163
I0424 13:41:39.396035 32254 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 21.329831ms
I0424 13:41:39.396128 32254 replica.cpp:712] Persisted action at 1
I0424 13:41:39.397290 32259 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0424 13:41:39.421186 32259 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 23.83522ms
I0424 13:41:39.421291 32259 replica.cpp:712] Persisted action at 1
I0424 13:41:39.421334 32259 replica.cpp:697] Replica learned APPEND action at position 1
I0424 13:41:39.422900 32251 registrar.cpp:508] Successfully updated the 'registry' in 50.81472ms
I0424 13:41:39.423070 32251 registrar.cpp:394] Successfully recovered registrar
I0424 13:41:39.423168 32247 log.cpp:567] Attempting to truncate the log to 1
I0424 13:41:39.423339 32246 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0424 13:41:39.423741 32251 master.cpp:1740] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0424 13:41:39.423789 32258 hierarchical.cpp:169] Skipping recovery of hierarchical allocator: nothing to recover
I0424 13:41:39.424928 32250 replica.cpp:537] Replica received write request for position 2 from (16914)@172.17.0.1:57163
I0424 13:41:39.446458 32250 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.455891ms
I0424 13:41:39.446563 32250 replica.cpp:712] Persisted action at 2
I0424 13:41:39.447597 32246 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0424 13:41:39.471642 32246 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.978842ms
I0424 13:41:39.471807 32246 leveldb.cpp:399] Deleting ~1 keys from leveldb took 82184ns
I0424 13:41:39.471834 32246 replica.cpp:712] Persisted action at 2
I0424 13:41:39.471869 32246 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0424 13:41:39.477720 32256 process.cpp:3171] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0424 13:41:39.478863 32256 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:50249
I0424 13:41:39.481410 32255 master.cpp:1189] Master terminating
[ OK ] ContentType/SchedulerHttpApiTest.UnsupportedContentMediaType/1 (402 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.Subscribe/0
I0424 13:41:39.490384 32226 cluster.cpp:149] Creating default 'local' authorizer
I0424 13:41:39.576046 32226 leveldb.cpp:174] Opened db in 85.320664ms
I0424 13:41:39.597255 32226 leveldb.cpp:181] Compacted db in 21.120289ms
I0424 13:41:39.597359 32226 leveldb.cpp:196] Created db iterator in 30539ns
I0424 13:41:39.597378 32226 leveldb.cpp:202] Seeked to beginning of db in 3451ns
I0424 13:41:39.597388 32226 leveldb.cpp:271] Iterated through 0 keys in the db in 260ns
I0424 13:41:39.597445 32226 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0424 13:41:39.598135 32246 recover.cpp:447] Starting replica recovery
I0424 13:41:39.598414 32246 recover.cpp:473] Replica is in EMPTY status
I0424 13:41:39.599669 32247 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16920)@172.17.0.1:57163
I0424 13:41:39.600126 32251 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0424 13:41:39.600625 32247 recover.cpp:564] Updating replica status to STARTING
I0424 13:41:39.602088 32253 master.cpp:382] Master 19c22aa7-56e3-414b-afd0-ed2645499f59 (1b2c79d6c5dc) started on 172.17.0.1:57163
I0424 13:41:39.602111 32253 master.cpp:384] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="true" --authenticate_http="true" --authenticate_http_frameworks="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/FgsRuM/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/FgsRuM/master" --zk_session_timeout="10secs"
I0424 13:41:39.602514 32253 master.cpp:433] Master only allowing authenticated frameworks to register
I0424 13:41:39.602527 32253 master.cpp:439] Master only allowing authenticated agents to register
I0424 13:41:39.602535 32253 master.cpp:445] Master only allowing authenticated HTTP frameworks to register
I0424 13:41:39.602545 32253 credentials.hpp:37] Loading credentials for authentication from '/tmp/FgsRuM/credentials'
I0424 13:41:39.602936 32253 master.cpp:489] Using default 'crammd5' authenticator
I0424 13:41:39.603080 32253 master.cpp:560] Using default 'basic' HTTP authenticator
I0424 13:41:39.603210 32253 master.cpp:640] Using default 'basic' HTTP framework authenticator
I0424 13:41:39.603303 32253 master.cpp:687] Authorization enabled
I0424 13:41:39.606420 32258 hierarchical.cpp:142] Initialized hierarchical allocator process
I0424 13:41:39.606595 32258 whitelist_watcher.cpp:77] No whitelist given
I0424 13:41:39.608922 32253 master.cpp:1932] The newly elected leader is master@172.17.0.1:57163 with id 19c22aa7-56e3-414b-afd0-ed2645499f59
I0424 13:41:39.609042 32253 master.cpp:1945] Elected as the leading master!
I0424 13:41:39.609159 32253 master.cpp:1632] Recovering from registrar
I0424 13:41:39.609568 32253 registrar.cpp:331] Recovering registrar
I0424 13:41:39.634487 32251 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.682695ms
I0424 13:41:39.634585 32251 replica.cpp:320] Persisted replica status to STARTING
I0424 13:41:39.634973 32258 recover.cpp:473] Replica is in STARTING status
I0424 13:41:39.636209 32246 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16923)@172.17.0.1:57163
I0424 13:41:39.636742 32257 recover.cpp:193] Received a recover response from a replica in STARTING status
I0424 13:41:39.637418 32257 recover.cpp:564] Updating replica status to VOTING
I0424 13:41:39.667940 32257 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.216953ms
I0424 13:41:39.668035 32257 replica.cpp:320] Persisted replica status to VOTING
I0424 13:41:39.668306 32257 recover.cpp:578] Successfully joined the Paxos group
I0424 13:41:39.668536 32257 recover.cpp:462] Recover process terminated
I0424 13:41:39.669353 32257 log.cpp:524] Attempting to start the writer
I0424 13:41:39.671175 32257 replica.cpp:493] Replica received implicit promise request from (16924)@172.17.0.1:57163 with proposal 1
I0424 13:41:39.702497 32257 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.264137ms
I0424 13:41:39.702595 32257 replica.cpp:342] Persisted promised to 1
I0424 13:41:39.704248 32257 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0424 13:41:39.706229 32257 replica.cpp:388] Replica received explicit promise request from (16925)@172.17.0.1:57163 for position 0 with proposal 2
I0424 13:41:39.738698 32257 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 32.39149ms
I0424 13:41:39.738791 32257 replica.cpp:712] Persisted action at 0
I0424 13:41:39.740368 32252 replica.cpp:537] Replica received write request for position 0 from (16926)@172.17.0.1:57163
I0424 13:41:39.740460 32252 leveldb.cpp:436] Reading position from leveldb took 46736ns
I0424 13:41:39.763834 32252 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.305981ms
I0424 13:41:39.763921 32252 replica.cpp:712] Persisted action at 0
I0424 13:41:39.765043 32252 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0424 13:41:39.789150 32252 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.050471ms
I0424 13:41:39.789242 32252 replica.cpp:712] Persisted action at 0
I0424 13:41:39.789280 32252 replica.cpp:697] Replica learned NOP action at position 0
I0424 13:41:39.790594 32252 log.cpp:540] Writer started with ending position 0
I0424 13:41:39.792218 32252 leveldb.cpp:436] Reading position from leveldb took 76048ns
I0424 13:41:39.793748 32250 registrar.cpp:364] Successfully fetched the registry (0B) in 183.85792ms
I0424 13:41:39.793921 32250 registrar.cpp:463] Applied 1 operations in 49886ns; attempting to update the 'registry'
I0424 13:41:39.794795 32251 log.cpp:548] Attempting to append 170 bytes to the log
I0424 13:41:39.794965 32259 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0424 13:41:39.795994 32255 replica.cpp:537] Replica received write request for position 1 from (16927)@172.17.0.1:57163
I0424 13:41:39.828219 32255 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 32.158804ms
I0424 13:41:39.828315 32255 replica.cpp:712] Persisted action at 1
I0424 13:41:39.830003 32255 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0424 13:41:39.856271 32255 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 26.211813ms
I0424 13:41:39.856365 32255 replica.cpp:712] Persisted action at 1
I0424 13:41:39.856405 32255 replica.cpp:697] Replica learned APPEND action at position 1
I0424 13:41:39.858837 32255 registrar.cpp:508] Successfully updated the 'registry' in 64.835072ms
I0424 13:41:39.858988 32255 registrar.cpp:394] Successfully recovered registrar
I0424 13:41:39.859282 32255 log.cpp:567] Attempting to truncate the log to 1
I0424 13:41:39.859854 32254 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0424 13:41:39.860942 32248 replica.cpp:537] Replica received write request for position 2 from (16928)@172.17.0.1:57163
I0424 13:41:39.861042 32255 master.cpp:1740] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0424 13:41:39.861071 32260 hierarchical.cpp:169] Skipping recovery of hierarchical allocator: nothing to recover
I0424 13:41:39.881541 32248 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 20.529789ms
I0424 13:41:39.881633 32248 replica.cpp:712] Persisted action at 2
I0424 13:41:39.882592 32256 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0424 13:41:39.906847 32256 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.190249ms
I0424 13:41:39.907019 32256 leveldb.cpp:399] Deleting ~1 keys from leveldb took 87214ns
I0424 13:41:39.907054 32256 replica.cpp:712] Persisted action at 2
I0424 13:41:39.907096 32256 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0424 13:41:39.913877 32252 process.cpp:3171] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0424 13:41:39.914784 32254 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:50251
I0424 13:41:39.915087 32254 master.cpp:2199] Received subscription request for HTTP framework 'default'
I0424 13:41:39.915164 32254 master.cpp:1971] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0424 13:41:39.915467 32254 master.cpp:2291] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0424 13:41:39.916151 32251 hierarchical.cpp:264] Added framework 19c22aa7-56e3-414b-afd0-ed2645499f59-0000
I0424 13:41:39.916189 32254 master.hpp:1725] Sending heartbeat to 19c22aa7-56e3-414b-afd0-ed2645499f59-0000
I0424 13:41:39.916265 32251 hierarchical.cpp:1488] No resources available to allocate!
I0424 13:41:39.916306 32251 hierarchical.cpp:1583] No inverse offers to send out!
I0424 13:41:39.916379 32251 hierarchical.cpp:1139] Performed allocation for 0 agents in 202239ns
I0424 13:41:39.920708 32254 hierarchical.cpp:1488] No resources available to allocate!
I0424 13:41:39.920738 32251 master.hpp:1725] Sending heartbeat to 19c22aa7-56e3-414b-afd0-ed2645499f59-0000
I0424 13:41:39.920799 32254 hierarchical.cpp:1583] No inverse offers to send out!
I0424 13:41:39.920886 32254 hierarchical.cpp:1139] Performed allocation for 0 agents in 330728ns
I0424 13:41:39.929790 32252 master.cpp:1189] Master terminating
I0424 13:41:39.930312 32248 hierarchical.cpp:326] Removed framework 19c22aa7-56e3-414b-afd0-ed2645499f59-0000
[ OK ] ContentType/SchedulerHttpApiTest.Subscribe/0 (471 ms)
[ RUN ] ContentType/SchedulerHttpApiTest.Subscribe/1
I0424 13:41:39.961486 32226 cluster.cpp:149] Creating default 'local' authorizer
I0424 13:41:40.049074 32226 leveldb.cpp:174] Opened db in 86.269619ms
I0424 13:41:40.074393 32226 leveldb.cpp:181] Compacted db in 25.229628ms
I0424 13:41:40.074497 32226 leveldb.cpp:196] Created db iterator in 31436ns
I0424 13:41:40.074517 32226 leveldb.cpp:202] Seeked to beginning of db in 3607ns
I0424 13:41:40.074528 32226 leveldb.cpp:271] Iterated through 0 keys in the db in 294ns
I0424 13:41:40.074587 32226 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0424 13:41:40.075492 32260 recover.cpp:447] Starting replica recovery
I0424 13:41:40.076161 32260 recover.cpp:473] Replica is in EMPTY status
I0424 13:41:40.077682 32252 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16935)@172.17.0.1:57163
I0424 13:41:40.078224 32254 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0424 13:41:40.078891 32254 recover.cpp:564] Updating replica status to STARTING
I0424 13:41:40.079936 32253 master.cpp:382] Master 69c1359a-b5cf-4501-86ca-907c6db56ea1 (1b2c79d6c5dc) started on 172.17.0.1:57163
I0424 13:41:40.079967 32253 master.cpp:384] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="true" --authenticate_http="true" --authenticate_http_frameworks="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/vQKRNm/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/vQKRNm/master" --zk_session_timeout="10secs"
I0424 13:41:40.080436 32253 master.cpp:433] Master only allowing authenticated frameworks to register
I0424 13:41:40.080452 32253 master.cpp:439] Master only allowing authenticated agents to register
I0424 13:41:40.080461 32253 master.cpp:445] Master only allowing authenticated HTTP frameworks to register
I0424 13:41:40.080471 32253 credentials.hpp:37] Loading credentials for authentication from '/tmp/vQKRNm/credentials'
I0424 13:41:40.080889 32253 master.cpp:489] Using default 'crammd5' authenticator
I0424 13:41:40.081054 32253 master.cpp:560] Using default 'basic' HTTP authenticator
I0424 13:41:40.081202 32253 master.cpp:640] Using default 'basic' HTTP framework authenticator
I0424 13:41:40.081301 32253 master.cpp:687] Authorization enabled
I0424 13:41:40.084688 32250 whitelist_watcher.cpp:77] No whitelist given
I0424 13:41:40.087174 32251 master.cpp:1932] The newly elected leader is master@172.17.0.1:57163 with id 69c1359a-b5cf-4501-86ca-907c6db56ea1
I0424 13:41:40.087239 32251 master.cpp:1945] Elected as the leading master!
I0424 13:41:40.087262 32251 master.cpp:1632] Recovering from registrar
I0424 13:41:40.087576 32251 registrar.cpp:331] Recovering registrar
I0424 13:41:40.089519 32253 hierarchical.cpp:142] Initialized hierarchical allocator process
I0424 13:41:40.112689 32254 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.562543ms
I0424 13:41:40.112777 32254 replica.cpp:320] Persisted replica status to STARTING
I0424 13:41:40.113154 32250 recover.cpp:473] Replica is in STARTING status
I0424 13:41:40.114578 32250 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16938)@172.17.0.1:57163
I0424 13:41:40.115110 32246 recover.cpp:193] Received a recover response from a replica in STARTING status
I0424 13:41:40.115631 32260 recover.cpp:564] Updating replica status to VOTING
I0424 13:41:40.146368 32259 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.556598ms
I0424 13:41:40.146459 32259 replica.cpp:320] Persisted replica status to VOTING
I0424 13:41:40.146736 32258 recover.cpp:578] Successfully joined the Paxos group
I0424 13:41:40.146996 32258 recover.cpp:462] Recover process terminated
I0424 13:41:40.147784 32251 log.cpp:524] Attempting to start the writer
I0424 13:41:40.149797 32251 replica.cpp:493] Replica received implicit promise request from (16939)@172.17.0.1:57163 with proposal 1
I0424 13:41:40.174283 32251 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 24.407286ms
I0424 13:41:40.174384 32251 replica.cpp:342] Persisted promised to 1
I0424 13:41:40.175956 32251 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0424 13:41:40.177935 32256 replica.cpp:388] Replica received explicit promise request from (16940)@172.17.0.1:57163 for position 0 with proposal 2
Build timed out (after 180 minutes). Marking the build as failed.
I0424 13:41:40.199733 32256 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.712712ms
I0424 13:41:40.199831 32256 replica.cpp:712] Persisted action at 0
I0424 13:41:40.201500 32253 replica.cpp:537] Replica received write request for position 0 from (16941)@172.17.0.1:57163
I0424 13:41:40.201624 32253 leveldb.cpp:436] Reading position from leveldb took 64269ns
Build was aborted
I0424 13:41:40.241672 32253 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 39.961578ms
I0424 13:41:40.241773 32253 replica.cpp:712] Persisted action at 0
I0424 13:41:40.243021 32253 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0424 13:41:40.266803 32253 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.721724ms
I0424 13:41:40.266903 32253 replica.cpp:712] Persisted action at 0
I0424 13:41:40.266944 32253 replica.cpp:697] Replica learned NOP action at position 0
I0424 13:41:40.268370 32253 log.cpp:540] Writer started with ending position 0
I0424 13:41:40.270179 32253 leveldb.cpp:436] Reading position from leveldb took 81173ns
I0424 13:41:40.271788 32250 registrar.cpp:364] Successfully fetched the registry (0B) in 184.125952ms
I0424 13:41:40.271965 32250 registrar.cpp:463] Applied 1 operations in 46955ns; attempting to update the 'registry'
I0424 13:41:40.272939 32259 log.cpp:548] Attempting to append 170 bytes to the log
I0424 13:41:40.273242 32259 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0424 13:41:40.274550 32259 replica.cpp:537] Replica received write request for position 1 from (16942)@172.17.0.1:57163
I0424 13:41:40.316938 32259 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 42.245984ms
I0424 13:41:40.317036 32259 replica.cpp:712] Persisted action at 1
I0424 13:41:40.318683 32259 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0424 13:41:40.342219 32259 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 23.468115ms
I0424 13:41:40.342311 32259 replica.cpp:712] Persisted action at 1
I0424 13:41:40.342349 32259 replica.cpp:697] Replica learned APPEND action at position 1
I0424 13:41:40.344737 32259 registrar.cpp:508] Successfully updated the 'registry' in 72.68992ms
I0424 13:41:40.344902 32259 registrar.cpp:394] Successfully recovered registrar
I0424 13:41:40.345186 32259 log.cpp:567] Attempting to truncate the log to 1
I0424 13:41:40.345791 32255 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0424 13:41:40.346211 32259 master.cpp:1740] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0424 13:41:40.346246 32247 hierarchical.cpp:169] Skipping recovery of hierarchical allocator: nothing to recover
I0424 13:41:40.346953 32256 replica.cpp:537] Replica received write request for position 2 from (16943)@172.17.0.1:57163
I0424 13:41:40.374449 32256 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 27.424282ms
I0424 13:41:40.374537 32256 replica.cpp:712] Persisted action at 2
I0424 13:41:40.375700 32256 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
Jenkins build is back to normal : mesos-reviewbot #12701
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/12701/>