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/08 00:43:33 UTC
Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1955
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1955/changes>
Changes:
[vinodkone] Fixed a memory leak in long lived executor.
[vinodkone] Deleted the `run` method in long lived executor.
[vinodkone] Cleaned up the virtual overloads in long lived executor.
[vinodkone] Move long lived executor to use the v1 API.
------------------------------------------
[...truncated 181353 lines...]
I0407 22:43:33.535166 29299 master.hpp:1719] Sending heartbeat to a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
I0407 22:43:33.535372 29309 hierarchical.cpp:267] Added framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
I0407 22:43:33.535454 29309 hierarchical.cpp:1491] No resources available to allocate!
I0407 22:43:33.535485 29309 hierarchical.cpp:1586] No inverse offers to send out!
I0407 22:43:33.535538 29309 hierarchical.cpp:1142] Performed allocation for 0 agents in 157025ns
I0407 22:43:33.537406 29303 scheduler.cpp:660] Enqueuing event SUBSCRIBED received from http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:33.538067 29303 scheduler.cpp:660] Enqueuing event HEARTBEAT received from http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:33.539325 29309 scheduler.cpp:234] Sending REQUEST call to http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:33.540393 29300 process.cpp:3165] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0407 22:43:33.540752 29300 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:60090
I0407 22:43:33.540972 29300 master.cpp:2801] Processing REQUEST call for framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
I0407 22:43:33.541285 29300 hierarchical.cpp:592] Received resource request from framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
I0407 22:43:33.544817 29296 master.cpp:1275] Framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) disconnected
I0407 22:43:33.544844 29296 master.cpp:2658] Disconnecting framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
I0407 22:43:33.544867 29296 master.cpp:2682] Deactivating framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
I0407 22:43:33.544944 29296 master.cpp:1299] Giving framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) 0ns to failover
I0407 22:43:33.545146 29296 hierarchical.cpp:378] Deactivated framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
I0407 22:43:33.548176 29296 master.cpp:5376] Framework failover timeout, removing framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
I0407 22:43:33.548203 29296 master.cpp:6109] Removing framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
I0407 22:43:33.548461 29296 hierarchical.cpp:329] Removed framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
I0407 22:43:33.553081 29278 master.cpp:1089] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (468 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
I0407 22:43:33.572032 29278 cluster.cpp:149] Creating default 'local' authorizer
I0407 22:43:33.692395 29278 leveldb.cpp:174] Opened db in 119.97905ms
I0407 22:43:33.717500 29278 leveldb.cpp:181] Compacted db in 25.05029ms
I0407 22:43:33.717569 29278 leveldb.cpp:196] Created db iterator in 32981ns
I0407 22:43:33.717587 29278 leveldb.cpp:202] Seeked to beginning of db in 9098ns
I0407 22:43:33.717599 29278 leveldb.cpp:271] Iterated through 0 keys in the db in 7211ns
I0407 22:43:33.717649 29278 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0407 22:43:33.718222 29298 recover.cpp:447] Starting replica recovery
I0407 22:43:33.720281 29304 recover.cpp:473] Replica is in EMPTY status
I0407 22:43:33.722905 29306 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16658)@172.17.0.3:35855
I0407 22:43:33.723548 29306 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0407 22:43:33.724474 29304 recover.cpp:564] Updating replica status to STARTING
I0407 22:43:33.724983 29297 master.cpp:382] Master d2929ce6-157c-45b1-a02b-0b0264770ae3 (129e11060069) started on 172.17.0.3:35855
I0407 22:43:33.725025 29297 master.cpp:384] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/OcAOGs/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_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/OcAOGs/master" --zk_session_timeout="10secs"
I0407 22:43:33.725473 29297 master.cpp:435] Master allowing unauthenticated frameworks to register
I0407 22:43:33.725487 29297 master.cpp:438] Master only allowing authenticated agents to register
I0407 22:43:33.725497 29297 credentials.hpp:37] Loading credentials for authentication from '/tmp/OcAOGs/credentials'
I0407 22:43:33.725860 29297 master.cpp:480] Using default 'crammd5' authenticator
I0407 22:43:33.726011 29297 master.cpp:551] Using default 'basic' HTTP authenticator
I0407 22:43:33.726173 29297 master.cpp:589] Authorization enabled
I0407 22:43:33.726353 29300 whitelist_watcher.cpp:77] No whitelist given
I0407 22:43:33.726385 29311 hierarchical.cpp:145] Initialized hierarchical allocator process
I0407 22:43:33.729537 29303 master.cpp:1832] The newly elected leader is master@172.17.0.3:35855 with id d2929ce6-157c-45b1-a02b-0b0264770ae3
I0407 22:43:33.729670 29303 master.cpp:1845] Elected as the leading master!
I0407 22:43:33.729773 29303 master.cpp:1532] Recovering from registrar
I0407 22:43:33.730120 29303 registrar.cpp:331] Recovering registrar
I0407 22:43:33.743185 29309 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 18.457836ms
I0407 22:43:33.743227 29309 replica.cpp:320] Persisted replica status to STARTING
I0407 22:43:33.743520 29304 recover.cpp:473] Replica is in STARTING status
I0407 22:43:33.745204 29296 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16660)@172.17.0.3:35855
I0407 22:43:33.745813 29300 recover.cpp:193] Received a recover response from a replica in STARTING status
I0407 22:43:33.746477 29309 recover.cpp:564] Updating replica status to VOTING
I0407 22:43:33.767707 29309 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.960203ms
I0407 22:43:33.767889 29309 replica.cpp:320] Persisted replica status to VOTING
I0407 22:43:33.768352 29309 recover.cpp:578] Successfully joined the Paxos group
I0407 22:43:33.768724 29309 recover.cpp:462] Recover process terminated
I0407 22:43:33.769368 29296 log.cpp:659] Attempting to start the writer
I0407 22:43:33.771579 29297 replica.cpp:493] Replica received implicit promise request from (16661)@172.17.0.3:35855 with proposal 1
I0407 22:43:33.793212 29297 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.496269ms
I0407 22:43:33.793275 29297 replica.cpp:342] Persisted promised to 1
I0407 22:43:33.794962 29299 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0407 22:43:33.796274 29300 replica.cpp:388] Replica received explicit promise request from (16662)@172.17.0.3:35855 for position 0 with proposal 2
I0407 22:43:33.831436 29300 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 35.137552ms
I0407 22:43:33.831509 29300 replica.cpp:712] Persisted action at 0
I0407 22:43:33.833217 29298 replica.cpp:537] Replica received write request for position 0 from (16663)@172.17.0.3:35855
I0407 22:43:33.833304 29298 leveldb.cpp:436] Reading position from leveldb took 58613ns
I0407 22:43:33.873253 29298 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 39.924326ms
I0407 22:43:33.873325 29298 replica.cpp:712] Persisted action at 0
I0407 22:43:33.874440 29307 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0407 22:43:33.915058 29307 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 40.580699ms
I0407 22:43:33.915138 29307 replica.cpp:712] Persisted action at 0
I0407 22:43:33.915179 29307 replica.cpp:697] Replica learned NOP action at position 0
I0407 22:43:33.916415 29307 log.cpp:675] Writer started with ending position 0
I0407 22:43:33.917920 29307 leveldb.cpp:436] Reading position from leveldb took 75769ns
I0407 22:43:33.919996 29306 registrar.cpp:364] Successfully fetched the registry (0B) in 189.755904ms
I0407 22:43:33.920181 29306 registrar.cpp:463] Applied 1 operations in 56218ns; attempting to update the 'registry'
I0407 22:43:33.921454 29306 log.cpp:683] Attempting to append 170 bytes to the log
I0407 22:43:33.921658 29306 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0407 22:43:33.922703 29306 replica.cpp:537] Replica received write request for position 1 from (16664)@172.17.0.3:35855
I0407 22:43:33.965260 29306 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 42.428505ms
I0407 22:43:33.965317 29306 replica.cpp:712] Persisted action at 1
I0407 22:43:33.966289 29302 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0407 22:43:34.006958 29302 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.595288ms
I0407 22:43:34.007019 29302 replica.cpp:712] Persisted action at 1
I0407 22:43:34.007047 29302 replica.cpp:697] Replica learned APPEND action at position 1
I0407 22:43:34.008319 29302 registrar.cpp:508] Successfully updated the 'registry' in 88.067072ms
I0407 22:43:34.008491 29302 registrar.cpp:394] Successfully recovered registrar
I0407 22:43:34.008616 29311 log.cpp:702] Attempting to truncate the log to 1
I0407 22:43:34.008780 29302 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0407 22:43:34.008955 29311 master.cpp:1640] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0407 22:43:34.009169 29311 hierarchical.cpp:172] Skipping recovery of hierarchical allocator: nothing to recover
I0407 22:43:34.009928 29298 replica.cpp:537] Replica received write request for position 2 from (16665)@172.17.0.3:35855
I0407 22:43:34.057065 29298 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 47.115137ms
I0407 22:43:34.057111 29298 replica.cpp:712] Persisted action at 2
I0407 22:43:34.058159 29298 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0407 22:43:34.098841 29298 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.667512ms
I0407 22:43:34.098918 29298 leveldb.cpp:399] Deleting ~1 keys from leveldb took 56150ns
I0407 22:43:34.098948 29298 replica.cpp:712] Persisted action at 2
I0407 22:43:34.098974 29298 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0407 22:43:34.100977 29278 scheduler.cpp:175] Version: 0.29.0
I0407 22:43:34.101480 29310 scheduler.cpp:469] New master detected at master@172.17.0.3:35855
I0407 22:43:34.104208 29302 scheduler.cpp:358] Connected with the master at http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:34.106226 29299 scheduler.cpp:440] Re-detecting master
I0407 22:43:34.106591 29299 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.106703 29299 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.106921 29299 scheduler.cpp:469] New master detected at master@172.17.0.3:35855
I0407 22:43:34.109439 29305 scheduler.cpp:358] Connected with the master at http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:34.120393 29304 scheduler.cpp:444] Lost leading master
I0407 22:43:34.128222 29304 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.128386 29304 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.128876 29297 scheduler.cpp:278] Ignoring reconnect request from scheduler since we are disconnected
I0407 22:43:34.149577 29278 master.cpp:1089] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (589 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
I0407 22:43:34.160708 29278 cluster.cpp:149] Creating default 'local' authorizer
I0407 22:43:34.284922 29278 leveldb.cpp:174] Opened db in 124.01605ms
I0407 22:43:34.318502 29278 leveldb.cpp:181] Compacted db in 33.548132ms
I0407 22:43:34.318598 29278 leveldb.cpp:196] Created db iterator in 32743ns
I0407 22:43:34.318614 29278 leveldb.cpp:202] Seeked to beginning of db in 8438ns
I0407 22:43:34.318626 29278 leveldb.cpp:271] Iterated through 0 keys in the db in 7692ns
I0407 22:43:34.318675 29278 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0407 22:43:34.319649 29298 recover.cpp:447] Starting replica recovery
I0407 22:43:34.320418 29297 recover.cpp:473] Replica is in EMPTY status
I0407 22:43:34.322320 29309 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16669)@172.17.0.3:35855
I0407 22:43:34.322985 29309 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0407 22:43:34.324254 29303 recover.cpp:564] Updating replica status to STARTING
I0407 22:43:34.325510 29307 master.cpp:382] Master 9ec88334-b056-4673-930e-074355a030ef (129e11060069) started on 172.17.0.3:35855
I0407 22:43:34.325820 29307 master.cpp:384] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/TqtB8N/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_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/TqtB8N/master" --zk_session_timeout="10secs"
I0407 22:43:34.326524 29307 master.cpp:435] Master allowing unauthenticated frameworks to register
I0407 22:43:34.326637 29307 master.cpp:438] Master only allowing authenticated agents to register
I0407 22:43:34.326745 29307 credentials.hpp:37] Loading credentials for authentication from '/tmp/TqtB8N/credentials'
I0407 22:43:34.327327 29307 master.cpp:480] Using default 'crammd5' authenticator
I0407 22:43:34.327642 29307 master.cpp:551] Using default 'basic' HTTP authenticator
I0407 22:43:34.327967 29307 master.cpp:589] Authorization enabled
I0407 22:43:34.328367 29302 hierarchical.cpp:145] Initialized hierarchical allocator process
I0407 22:43:34.328435 29302 whitelist_watcher.cpp:77] No whitelist given
I0407 22:43:34.330946 29299 master.cpp:1832] The newly elected leader is master@172.17.0.3:35855 with id 9ec88334-b056-4673-930e-074355a030ef
I0407 22:43:34.330986 29299 master.cpp:1845] Elected as the leading master!
I0407 22:43:34.331001 29299 master.cpp:1532] Recovering from registrar
I0407 22:43:34.331282 29299 registrar.cpp:331] Recovering registrar
I0407 22:43:34.351889 29301 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.449575ms
I0407 22:43:34.351950 29301 replica.cpp:320] Persisted replica status to STARTING
I0407 22:43:34.352294 29301 recover.cpp:473] Replica is in STARTING status
I0407 22:43:34.354171 29301 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16671)@172.17.0.3:35855
I0407 22:43:34.354565 29301 recover.cpp:193] Received a recover response from a replica in STARTING status
I0407 22:43:34.355317 29308 recover.cpp:564] Updating replica status to VOTING
I0407 22:43:34.377023 29308 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.36009ms
I0407 22:43:34.377225 29308 replica.cpp:320] Persisted replica status to VOTING
I0407 22:43:34.377491 29308 recover.cpp:578] Successfully joined the Paxos group
I0407 22:43:34.377691 29308 recover.cpp:462] Recover process terminated
I0407 22:43:34.378443 29308 log.cpp:659] Attempting to start the writer
I0407 22:43:34.380103 29309 replica.cpp:493] Replica received implicit promise request from (16672)@172.17.0.3:35855 with proposal 1
I0407 22:43:34.393790 29309 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 13.658222ms
I0407 22:43:34.393851 29309 replica.cpp:342] Persisted promised to 1
I0407 22:43:34.394953 29302 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0407 22:43:34.396569 29297 replica.cpp:388] Replica received explicit promise request from (16673)@172.17.0.3:35855 for position 0 with proposal 2
I0407 22:43:34.410588 29297 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 13.99384ms
I0407 22:43:34.410648 29297 replica.cpp:712] Persisted action at 0
I0407 22:43:34.412385 29306 replica.cpp:537] Replica received write request for position 0 from (16674)@172.17.0.3:35855
I0407 22:43:34.412509 29306 leveldb.cpp:436] Reading position from leveldb took 66530ns
I0407 22:43:34.427356 29306 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 14.71772ms
I0407 22:43:34.427525 29306 replica.cpp:712] Persisted action at 0
I0407 22:43:34.428591 29298 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0407 22:43:34.444162 29298 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.464087ms
I0407 22:43:34.444386 29298 replica.cpp:712] Persisted action at 0
I0407 22:43:34.444563 29298 replica.cpp:697] Replica learned NOP action at position 0
I0407 22:43:34.445961 29298 log.cpp:675] Writer started with ending position 0
I0407 22:43:34.448225 29298 leveldb.cpp:436] Reading position from leveldb took 72324ns
I0407 22:43:34.449959 29301 registrar.cpp:364] Successfully fetched the registry (0B) in 118624us
I0407 22:43:34.450192 29301 registrar.cpp:463] Applied 1 operations in 141125ns; attempting to update the 'registry'
I0407 22:43:34.451187 29311 log.cpp:683] Attempting to append 170 bytes to the log
I0407 22:43:34.451658 29301 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0407 22:43:34.452664 29306 replica.cpp:537] Replica received write request for position 1 from (16675)@172.17.0.3:35855
I0407 22:43:34.469269 29306 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 16.580714ms
I0407 22:43:34.469333 29306 replica.cpp:712] Persisted action at 1
I0407 22:43:34.470687 29306 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0407 22:43:34.486049 29306 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 15.347979ms
I0407 22:43:34.486106 29306 replica.cpp:712] Persisted action at 1
I0407 22:43:34.486160 29306 replica.cpp:697] Replica learned APPEND action at position 1
I0407 22:43:34.488042 29307 registrar.cpp:508] Successfully updated the 'registry' in 37.76384ms
I0407 22:43:34.488186 29296 log.cpp:702] Attempting to truncate the log to 1
I0407 22:43:34.488824 29299 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0407 22:43:34.490442 29299 replica.cpp:537] Replica received write request for position 2 from (16676)@172.17.0.3:35855
I0407 22:43:34.491020 29307 registrar.cpp:394] Successfully recovered registrar
I0407 22:43:34.491515 29307 master.cpp:1640] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0407 22:43:34.491685 29303 hierarchical.cpp:172] Skipping recovery of hierarchical allocator: nothing to recover
I0407 22:43:34.511176 29299 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 20.372117ms
I0407 22:43:34.511369 29299 replica.cpp:712] Persisted action at 2
I0407 22:43:34.513206 29299 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0407 22:43:34.536279 29299 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.060111ms
I0407 22:43:34.536406 29299 leveldb.cpp:399] Deleting ~1 keys from leveldb took 77707ns
I0407 22:43:34.536557 29299 replica.cpp:712] Persisted action at 2
I0407 22:43:34.536694 29299 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0407 22:43:34.545011 29278 scheduler.cpp:175] Version: 0.29.0
I0407 22:43:34.545533 29311 scheduler.cpp:469] New master detected at master@172.17.0.3:35855
I0407 22:43:34.548821 29298 scheduler.cpp:358] Connected with the master at http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:34.551250 29309 scheduler.cpp:440] Re-detecting master
I0407 22:43:34.551614 29309 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.551749 29309 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.552273 29309 scheduler.cpp:469] New master detected at master@172.17.0.3:35855
I0407 22:43:34.554633 29306 scheduler.cpp:358] Connected with the master at http://172.17.0.3:35855/master/api/v1/scheduler
I0407 22:43:34.556355 29307 scheduler.cpp:444] Lost leading master
I0407 22:43:34.556821 29307 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.556948 29307 scheduler.cpp:391] Ignoring disconnection attempt from stale connection
I0407 22:43:34.558166 29307 scheduler.cpp:278] Ignoring reconnect request from scheduler since we are disconnected
I0407 22:43:34.582536 29278 master.cpp:1089] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (437 ms)
[----------] 28 tests from ContentType/SchedulerTest (22152 ms total)
[----------] Global test environment tear-down
[==========] 1059 tests from 130 test cases ran. (849386 ms total)
[ PASSED ] 1058 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MasterAllocatorTest/1.RebalancedForUpdatedWeights, where TypeParam = mesos::internal::tests::Module<mesos::master::allocator::Allocator, (mesos::internal::tests::ModuleID)6>
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[4]: *** [check-local] Error 1
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1460066014-22985
Untagged: mesos-1460066014-22985:latest
Deleted: 90940acb7da9218a3dce5c512a16554bbc3001c458feb3462def9caf3570f778
Deleted: a8b0f613c0d1f6bc75bde2d2b173fd072083c692fdb856dcce4d8825ba061395
Deleted: 48ca606dff75e538ce888c8f1a0aeb37c954f9fb0ce7976bb15509899c1f520c
Deleted: c631b92c92b447da71d74f05835b04616e89650c790f8c974b971d2405b88fa1
Deleted: c6bf725d00b56d54a409b0637078659bbf35d777d48fdeeea99990ebbefb6cb7
Deleted: c741b5dfdb46affc3a32d4dabc0434b2ed233208a87f58f66dfe13d63dd82df7
Deleted: b39d2acfa97ab056ad3bf37a9fbd2f14625400b27652694f8f1b2c493d0006f1
Deleted: 722eb2749b09109e8ceade9c37929a5c71679d746b366b052fdf10b365abf7bd
Deleted: e2790626988ff17c2979a75626a00f633b472c892bb43b7ad330222d6ef2b910
Deleted: d8984a410c448ee4f414280b422c4aa3789821b2a3b4a57c53a8804001bf1cf0
Deleted: bba6521cb2c41d4fe5439858b1ac50240e1e090b16d570a88fc6d1ba0bdfe1d6
Deleted: e7b0ec4e4ce154529b3b5e0f9bec73072b69cca1611f0e7c7ff05c5616151706
Deleted: dd832db027d923c8c4bcbe12ad0c93f2eddd853f594989ca253296be1f41e1eb
Deleted: b6d05cd8d607423fdfab49bf4783c3065584f7b92baeeabf8e6f579072009670
Deleted: 0f4136cdbd63ffaa94a8a5a19dd85eb1ab708d97a82baf497fae00038c5e0438
Deleted: 6b926e415e33827e7b872a2bae8035cbe98da98ff6bc085aa9a65cb05bf85dec
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1955
Posted by Greg Mann <gr...@mesosphere.io>.
Yep, I believe it's new. Created a ticket here:
https://issues.apache.org/jira/browse/MESOS-5146
On Thu, Apr 7, 2016 at 3:47 PM, Vinod Kone <vi...@apache.org> wrote:
> is this new?
>
> On Thu, Apr 7, 2016 at 3:43 PM, Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
>
> > See <
> >
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1955/changes
> > >
> >
> > Changes:
> >
> > [vinodkone] Fixed a memory leak in long lived executor.
> >
> > [vinodkone] Deleted the `run` method in long lived executor.
> >
> > [vinodkone] Cleaned up the virtual overloads in long lived executor.
> >
> > [vinodkone] Move long lived executor to use the v1 API.
> >
> > ------------------------------------------
> > [...truncated 181353 lines...]
> > I0407 22:43:33.535166 29299 master.hpp:1719] Sending heartbeat to
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> > I0407 22:43:33.535372 29309 hierarchical.cpp:267] Added framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> > I0407 22:43:33.535454 29309 hierarchical.cpp:1491] No resources available
> > to allocate!
> > I0407 22:43:33.535485 29309 hierarchical.cpp:1586] No inverse offers to
> > send out!
> > I0407 22:43:33.535538 29309 hierarchical.cpp:1142] Performed allocation
> > for 0 agents in 157025ns
> > I0407 22:43:33.537406 29303 scheduler.cpp:660] Enqueuing event SUBSCRIBED
> > received from http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:33.538067 29303 scheduler.cpp:660] Enqueuing event HEARTBEAT
> received
> > from http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:33.539325 29309 scheduler.cpp:234] Sending REQUEST call to
> > http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:33.540393 29300 process.cpp:3165] Handling HTTP event for process
> > 'master' with path: '/master/api/v1/scheduler'
> > I0407 22:43:33.540752 29300 http.cpp:313] HTTP POST for
> > /master/api/v1/scheduler from 172.17.0.3:60090
> > I0407 22:43:33.540972 29300 master.cpp:2801] Processing REQUEST call for
> > framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> > I0407 22:43:33.541285 29300 hierarchical.cpp:592] Received resource
> > request from framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> > I0407 22:43:33.544817 29296 master.cpp:1275] Framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) disconnected
> > I0407 22:43:33.544844 29296 master.cpp:2658] Disconnecting framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> > I0407 22:43:33.544867 29296 master.cpp:2682] Deactivating framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> > I0407 22:43:33.544944 29296 master.cpp:1299] Giving framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) 0ns to failover
> > I0407 22:43:33.545146 29296 hierarchical.cpp:378] Deactivated framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> > I0407 22:43:33.548176 29296 master.cpp:5376] Framework failover timeout,
> > removing framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> > I0407 22:43:33.548203 29296 master.cpp:6109] Removing framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> > I0407 22:43:33.548461 29296 hierarchical.cpp:329] Removed framework
> > a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> > I0407 22:43:33.553081 29278 master.cpp:1089] Master terminating
> > [ OK ] ContentType/SchedulerTest.Request/1 (468 ms)
> > [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
> > I0407 22:43:33.572032 29278 cluster.cpp:149] Creating default 'local'
> > authorizer
> > I0407 22:43:33.692395 29278 leveldb.cpp:174] Opened db in 119.97905ms
> > I0407 22:43:33.717500 29278 leveldb.cpp:181] Compacted db in 25.05029ms
> > I0407 22:43:33.717569 29278 leveldb.cpp:196] Created db iterator in
> 32981ns
> > I0407 22:43:33.717587 29278 leveldb.cpp:202] Seeked to beginning of db in
> > 9098ns
> > I0407 22:43:33.717599 29278 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 7211ns
> > I0407 22:43:33.717649 29278 replica.cpp:779] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I0407 22:43:33.718222 29298 recover.cpp:447] Starting replica recovery
> > I0407 22:43:33.720281 29304 recover.cpp:473] Replica is in EMPTY status
> > I0407 22:43:33.722905 29306 replica.cpp:673] Replica in EMPTY status
> > received a broadcasted recover request from (16658)@172.17.0.3:35855
> > I0407 22:43:33.723548 29306 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I0407 22:43:33.724474 29304 recover.cpp:564] Updating replica status to
> > STARTING
> > I0407 22:43:33.724983 29297 master.cpp:382] Master
> > d2929ce6-157c-45b1-a02b-0b0264770ae3 (129e11060069) started on
> > 172.17.0.3:35855
> > I0407 22:43:33.725025 29297 master.cpp:384] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_http="true"
> > --authenticate_slaves="true" --authenticators="crammd5"
> > --authorizers="local" --credentials="/tmp/OcAOGs/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_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/OcAOGs/master" --zk_session_timeout="10secs"
> > I0407 22:43:33.725473 29297 master.cpp:435] Master allowing
> > unauthenticated frameworks to register
> > I0407 22:43:33.725487 29297 master.cpp:438] Master only allowing
> > authenticated agents to register
> > I0407 22:43:33.725497 29297 credentials.hpp:37] Loading credentials for
> > authentication from '/tmp/OcAOGs/credentials'
> > I0407 22:43:33.725860 29297 master.cpp:480] Using default 'crammd5'
> > authenticator
> > I0407 22:43:33.726011 29297 master.cpp:551] Using default 'basic' HTTP
> > authenticator
> > I0407 22:43:33.726173 29297 master.cpp:589] Authorization enabled
> > I0407 22:43:33.726353 29300 whitelist_watcher.cpp:77] No whitelist given
> > I0407 22:43:33.726385 29311 hierarchical.cpp:145] Initialized
> hierarchical
> > allocator process
> > I0407 22:43:33.729537 29303 master.cpp:1832] The newly elected leader is
> > master@172.17.0.3:35855 with id d2929ce6-157c-45b1-a02b-0b0264770ae3
> > I0407 22:43:33.729670 29303 master.cpp:1845] Elected as the leading
> master!
> > I0407 22:43:33.729773 29303 master.cpp:1532] Recovering from registrar
> > I0407 22:43:33.730120 29303 registrar.cpp:331] Recovering registrar
> > I0407 22:43:33.743185 29309 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 18.457836ms
> > I0407 22:43:33.743227 29309 replica.cpp:320] Persisted replica status to
> > STARTING
> > I0407 22:43:33.743520 29304 recover.cpp:473] Replica is in STARTING
> status
> > I0407 22:43:33.745204 29296 replica.cpp:673] Replica in STARTING status
> > received a broadcasted recover request from (16660)@172.17.0.3:35855
> > I0407 22:43:33.745813 29300 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I0407 22:43:33.746477 29309 recover.cpp:564] Updating replica status to
> > VOTING
> > I0407 22:43:33.767707 29309 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 20.960203ms
> > I0407 22:43:33.767889 29309 replica.cpp:320] Persisted replica status to
> > VOTING
> > I0407 22:43:33.768352 29309 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I0407 22:43:33.768724 29309 recover.cpp:462] Recover process terminated
> > I0407 22:43:33.769368 29296 log.cpp:659] Attempting to start the writer
> > I0407 22:43:33.771579 29297 replica.cpp:493] Replica received implicit
> > promise request from (16661)@172.17.0.3:35855 with proposal 1
> > I0407 22:43:33.793212 29297 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 21.496269ms
> > I0407 22:43:33.793275 29297 replica.cpp:342] Persisted promised to 1
> > I0407 22:43:33.794962 29299 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I0407 22:43:33.796274 29300 replica.cpp:388] Replica received explicit
> > promise request from (16662)@172.17.0.3:35855 for position 0 with
> > proposal 2
> > I0407 22:43:33.831436 29300 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 35.137552ms
> > I0407 22:43:33.831509 29300 replica.cpp:712] Persisted action at 0
> > I0407 22:43:33.833217 29298 replica.cpp:537] Replica received write
> > request for position 0 from (16663)@172.17.0.3:35855
> > I0407 22:43:33.833304 29298 leveldb.cpp:436] Reading position from
> leveldb
> > took 58613ns
> > I0407 22:43:33.873253 29298 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 39.924326ms
> > I0407 22:43:33.873325 29298 replica.cpp:712] Persisted action at 0
> > I0407 22:43:33.874440 29307 replica.cpp:691] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I0407 22:43:33.915058 29307 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 40.580699ms
> > I0407 22:43:33.915138 29307 replica.cpp:712] Persisted action at 0
> > I0407 22:43:33.915179 29307 replica.cpp:697] Replica learned NOP action
> at
> > position 0
> > I0407 22:43:33.916415 29307 log.cpp:675] Writer started with ending
> > position 0
> > I0407 22:43:33.917920 29307 leveldb.cpp:436] Reading position from
> leveldb
> > took 75769ns
> > I0407 22:43:33.919996 29306 registrar.cpp:364] Successfully fetched the
> > registry (0B) in 189.755904ms
> > I0407 22:43:33.920181 29306 registrar.cpp:463] Applied 1 operations in
> > 56218ns; attempting to update the 'registry'
> > I0407 22:43:33.921454 29306 log.cpp:683] Attempting to append 170 bytes
> to
> > the log
> > I0407 22:43:33.921658 29306 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I0407 22:43:33.922703 29306 replica.cpp:537] Replica received write
> > request for position 1 from (16664)@172.17.0.3:35855
> > I0407 22:43:33.965260 29306 leveldb.cpp:341] Persisting action (189
> bytes)
> > to leveldb took 42.428505ms
> > I0407 22:43:33.965317 29306 replica.cpp:712] Persisted action at 1
> > I0407 22:43:33.966289 29302 replica.cpp:691] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I0407 22:43:34.006958 29302 leveldb.cpp:341] Persisting action (191
> bytes)
> > to leveldb took 40.595288ms
> > I0407 22:43:34.007019 29302 replica.cpp:712] Persisted action at 1
> > I0407 22:43:34.007047 29302 replica.cpp:697] Replica learned APPEND
> action
> > at position 1
> > I0407 22:43:34.008319 29302 registrar.cpp:508] Successfully updated the
> > 'registry' in 88.067072ms
> > I0407 22:43:34.008491 29302 registrar.cpp:394] Successfully recovered
> > registrar
> > I0407 22:43:34.008616 29311 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I0407 22:43:34.008780 29302 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I0407 22:43:34.008955 29311 master.cpp:1640] Recovered 0 agents from the
> > Registry (131B) ; allowing 10mins for agents to re-register
> > I0407 22:43:34.009169 29311 hierarchical.cpp:172] Skipping recovery of
> > hierarchical allocator: nothing to recover
> > I0407 22:43:34.009928 29298 replica.cpp:537] Replica received write
> > request for position 2 from (16665)@172.17.0.3:35855
> > I0407 22:43:34.057065 29298 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 47.115137ms
> > I0407 22:43:34.057111 29298 replica.cpp:712] Persisted action at 2
> > I0407 22:43:34.058159 29298 replica.cpp:691] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I0407 22:43:34.098841 29298 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 40.667512ms
> > I0407 22:43:34.098918 29298 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 56150ns
> > I0407 22:43:34.098948 29298 replica.cpp:712] Persisted action at 2
> > I0407 22:43:34.098974 29298 replica.cpp:697] Replica learned TRUNCATE
> > action at position 2
> > I0407 22:43:34.100977 29278 scheduler.cpp:175] Version: 0.29.0
> > I0407 22:43:34.101480 29310 scheduler.cpp:469] New master detected at
> > master@172.17.0.3:35855
> > I0407 22:43:34.104208 29302 scheduler.cpp:358] Connected with the master
> > at http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:34.106226 29299 scheduler.cpp:440] Re-detecting master
> > I0407 22:43:34.106591 29299 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.106703 29299 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.106921 29299 scheduler.cpp:469] New master detected at
> > master@172.17.0.3:35855
> > I0407 22:43:34.109439 29305 scheduler.cpp:358] Connected with the master
> > at http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:34.120393
> > 29304 scheduler.cpp:444] Lost leading master
> > I0407 22:43:34.128222 29304 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.128386 29304 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.128876 29297 scheduler.cpp:278] Ignoring reconnect request
> > from scheduler since we are disconnected
> > I0407 22:43:34.149577 29278 master.cpp:1089] Master terminating
> > [ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (589 ms)
> > [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
> > I0407 22:43:34.160708 29278 cluster.cpp:149] Creating default 'local'
> > authorizer
> > I0407 22:43:34.284922 29278 leveldb.cpp:174] Opened db in 124.01605ms
> > I0407 22:43:34.318502 29278 leveldb.cpp:181] Compacted db in 33.548132ms
> > I0407 22:43:34.318598 29278 leveldb.cpp:196] Created db iterator in
> 32743ns
> > I0407 22:43:34.318614 29278 leveldb.cpp:202] Seeked to beginning of db in
> > 8438ns
> > I0407 22:43:34.318626 29278 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 7692ns
> > I0407 22:43:34.318675 29278 replica.cpp:779] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I0407 22:43:34.319649 29298 recover.cpp:447] Starting replica recovery
> > I0407 22:43:34.320418 29297 recover.cpp:473] Replica is in EMPTY status
> > I0407 22:43:34.322320 29309 replica.cpp:673] Replica in EMPTY status
> > received a broadcasted recover request from (16669)@172.17.0.3:35855
> > I0407 22:43:34.322985 29309 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I0407 22:43:34.324254 29303 recover.cpp:564] Updating replica status to
> > STARTING
> > I0407 22:43:34.325510 29307 master.cpp:382] Master
> > 9ec88334-b056-4673-930e-074355a030ef (129e11060069) started on
> > 172.17.0.3:35855
> > I0407 22:43:34.325820 29307 master.cpp:384] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_http="true"
> > --authenticate_slaves="true" --authenticators="crammd5"
> > --authorizers="local" --credentials="/tmp/TqtB8N/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_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/TqtB8N/master" --zk_session_timeout="10secs"
> > I0407 22:43:34.326524 29307 master.cpp:435] Master allowing
> > unauthenticated frameworks to register
> > I0407 22:43:34.326637 29307 master.cpp:438] Master only allowing
> > authenticated agents to register
> > I0407 22:43:34.326745 29307 credentials.hpp:37] Loading credentials for
> > authentication from '/tmp/TqtB8N/credentials'
> > I0407 22:43:34.327327 29307 master.cpp:480] Using default 'crammd5'
> > authenticator
> > I0407 22:43:34.327642 29307 master.cpp:551] Using default 'basic' HTTP
> > authenticator
> > I0407 22:43:34.327967 29307 master.cpp:589] Authorization enabled
> > I0407 22:43:34.328367 29302 hierarchical.cpp:145] Initialized
> hierarchical
> > allocator process
> > I0407 22:43:34.328435 29302 whitelist_watcher.cpp:77] No whitelist given
> > I0407 22:43:34.330946 29299 master.cpp:1832] The newly elected leader is
> > master@172.17.0.3:35855 with id 9ec88334-b056-4673-930e-074355a030ef
> > I0407 22:43:34.330986 29299 master.cpp:1845] Elected as the leading
> master!
> > I0407 22:43:34.331001 29299 master.cpp:1532] Recovering from registrar
> > I0407 22:43:34.331282 29299 registrar.cpp:331] Recovering registrar
> > I0407 22:43:34.351889 29301 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 27.449575ms
> > I0407 22:43:34.351950 29301 replica.cpp:320] Persisted replica status to
> > STARTING
> > I0407 22:43:34.352294 29301 recover.cpp:473] Replica is in STARTING
> status
> > I0407 22:43:34.354171 29301 replica.cpp:673] Replica in STARTING status
> > received a broadcasted recover request from (16671)@172.17.0.3:35855
> > I0407 22:43:34.354565 29301 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I0407 22:43:34.355317 29308 recover.cpp:564] Updating replica status to
> > VOTING
> > I0407 22:43:34.377023 29308 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 21.36009ms
> > I0407 22:43:34.377225 29308 replica.cpp:320] Persisted replica status to
> > VOTING
> > I0407 22:43:34.377491 29308 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I0407 22:43:34.377691 29308 recover.cpp:462] Recover process terminated
> > I0407 22:43:34.378443 29308 log.cpp:659] Attempting to start the writer
> > I0407 22:43:34.380103 29309 replica.cpp:493] Replica received implicit
> > promise request from (16672)@172.17.0.3:35855 with proposal 1
> > I0407 22:43:34.393790 29309 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 13.658222ms
> > I0407 22:43:34.393851 29309 replica.cpp:342] Persisted promised to 1
> > I0407 22:43:34.394953 29302 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I0407 22:43:34.396569 29297 replica.cpp:388] Replica received explicit
> > promise request from (16673)@172.17.0.3:35855 for position 0 with
> > proposal 2
> > I0407 22:43:34.410588 29297 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 13.99384ms
> > I0407 22:43:34.410648 29297 replica.cpp:712] Persisted action at 0
> > I0407 22:43:34.412385 29306 replica.cpp:537] Replica received write
> > request for position 0 from (16674)@172.17.0.3:35855
> > I0407 22:43:34.412509 29306 leveldb.cpp:436] Reading position from
> leveldb
> > took 66530ns
> > I0407 22:43:34.427356 29306 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 14.71772ms
> > I0407 22:43:34.427525 29306 replica.cpp:712] Persisted action at 0
> > I0407 22:43:34.428591 29298 replica.cpp:691] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I0407 22:43:34.444162 29298 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 15.464087ms
> > I0407 22:43:34.444386 29298 replica.cpp:712] Persisted action at 0
> > I0407 22:43:34.444563 29298 replica.cpp:697] Replica learned NOP action
> at
> > position 0
> > I0407 22:43:34.445961 29298 log.cpp:675] Writer started with ending
> > position 0
> > I0407 22:43:34.448225 29298 leveldb.cpp:436] Reading position from
> leveldb
> > took 72324ns
> > I0407 22:43:34.449959 29301 registrar.cpp:364] Successfully fetched the
> > registry (0B) in 118624us
> > I0407 22:43:34.450192 29301 registrar.cpp:463] Applied 1 operations in
> > 141125ns; attempting to update the 'registry'
> > I0407 22:43:34.451187 29311 log.cpp:683] Attempting to append 170 bytes
> to
> > the log
> > I0407 22:43:34.451658 29301 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I0407 22:43:34.452664 29306 replica.cpp:537] Replica received write
> > request for position 1 from (16675)@172.17.0.3:35855
> > I0407 22:43:34.469269 29306 leveldb.cpp:341] Persisting action (189
> bytes)
> > to leveldb took 16.580714ms
> > I0407 22:43:34.469333 29306 replica.cpp:712] Persisted action at 1
> > I0407 22:43:34.470687 29306 replica.cpp:691] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I0407 22:43:34.486049 29306 leveldb.cpp:341] Persisting action (191
> bytes)
> > to leveldb took 15.347979ms
> > I0407 22:43:34.486106 29306 replica.cpp:712] Persisted action at 1
> > I0407 22:43:34.486160 29306 replica.cpp:697] Replica learned APPEND
> action
> > at position 1
> > I0407 22:43:34.488042 29307 registrar.cpp:508] Successfully updated the
> > 'registry' in 37.76384ms
> > I0407 22:43:34.488186 29296 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I0407 22:43:34.488824 29299 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I0407 22:43:34.490442 29299 replica.cpp:537] Replica received write
> > request for position 2 from (16676)@172.17.0.3:35855
> > I0407 22:43:34.491020 29307 registrar.cpp:394] Successfully recovered
> > registrar
> > I0407 22:43:34.491515 29307 master.cpp:1640] Recovered 0 agents from the
> > Registry (131B) ; allowing 10mins for agents to re-register
> > I0407 22:43:34.491685 29303 hierarchical.cpp:172] Skipping recovery of
> > hierarchical allocator: nothing to recover
> > I0407 22:43:34.511176 29299 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 20.372117ms
> > I0407 22:43:34.511369 29299 replica.cpp:712] Persisted action at 2
> > I0407 22:43:34.513206 29299 replica.cpp:691] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I0407 22:43:34.536279 29299 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 23.060111ms
> > I0407 22:43:34.536406 29299 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 77707ns
> > I0407 22:43:34.536557 29299 replica.cpp:712] Persisted action at 2
> > I0407 22:43:34.536694 29299 replica.cpp:697] Replica learned TRUNCATE
> > action at position 2
> > I0407 22:43:34.545011 29278 scheduler.cpp:175] Version: 0.29.0
> > I0407 22:43:34.545533 29311 scheduler.cpp:469] New master detected at
> > master@172.17.0.3:35855
> > I0407 22:43:34.548821 29298 scheduler.cpp:358] Connected with the master
> > at http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:34.551250 29309 scheduler.cpp:440] Re-detecting master
> > I0407 22:43:34.551614 29309 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.551749 29309 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.552273 29309 scheduler.cpp:469] New master detected at
> > master@172.17.0.3:35855
> > I0407 22:43:34.554633 29306 scheduler.cpp:358] Connected with the master
> > at http://172.17.0.3:35855/master/api/v1/scheduler
> > I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> > 22:43:34.556355 29307 scheduler.cpp:444] Lost leading master
> > I0407 22:43:34.556821 29307 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.556948 29307 scheduler.cpp:391] Ignoring disconnection
> > attempt from stale connection
> > I0407 22:43:34.558166 29307 scheduler.cpp:278] Ignoring reconnect request
> > from scheduler since we are disconnected
> > I0407 22:43:34.582536 29278 master.cpp:1089] Master terminating
> > [ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (437 ms)
> > [----------] 28 tests from ContentType/SchedulerTest (22152 ms total)
> >
> > [----------] Global test environment tear-down
> > [==========] 1059 tests from 130 test cases ran. (849386 ms total)
> > [ PASSED ] 1058 tests.
> > [ FAILED ] 1 test, listed below:
> > [ FAILED ] MasterAllocatorTest/1.RebalancedForUpdatedWeights, where
> > TypeParam =
> > mesos::internal::tests::Module<mesos::master::allocator::Allocator,
> > (mesos::internal::tests::ModuleID)6>
> >
> > 1 FAILED TEST
> > YOU HAVE 6 DISABLED TESTS
> >
> > make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> > make[4]: *** [check-local] Error 1
> > make[3]: *** [check-am] Error 2
> > make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> > make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> > make[2]: *** [check] Error 2
> > make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> > make[1]: *** [check-recursive] Error 1
> > make: *** [distcheck] Error 1
> > + docker rmi mesos-1460066014-22985
> > Untagged: mesos-1460066014-22985:latest
> > Deleted: 90940acb7da9218a3dce5c512a16554bbc3001c458feb3462def9caf3570f778
> > Deleted: a8b0f613c0d1f6bc75bde2d2b173fd072083c692fdb856dcce4d8825ba061395
> > Deleted: 48ca606dff75e538ce888c8f1a0aeb37c954f9fb0ce7976bb15509899c1f520c
> > Deleted: c631b92c92b447da71d74f05835b04616e89650c790f8c974b971d2405b88fa1
> > Deleted: c6bf725d00b56d54a409b0637078659bbf35d777d48fdeeea99990ebbefb6cb7
> > Deleted: c741b5dfdb46affc3a32d4dabc0434b2ed233208a87f58f66dfe13d63dd82df7
> > Deleted: b39d2acfa97ab056ad3bf37a9fbd2f14625400b27652694f8f1b2c493d0006f1
> > Deleted: 722eb2749b09109e8ceade9c37929a5c71679d746b366b052fdf10b365abf7bd
> > Deleted: e2790626988ff17c2979a75626a00f633b472c892bb43b7ad330222d6ef2b910
> > Deleted: d8984a410c448ee4f414280b422c4aa3789821b2a3b4a57c53a8804001bf1cf0
> > Deleted: bba6521cb2c41d4fe5439858b1ac50240e1e090b16d570a88fc6d1ba0bdfe1d6
> > Deleted: e7b0ec4e4ce154529b3b5e0f9bec73072b69cca1611f0e7c7ff05c5616151706
> > Deleted: dd832db027d923c8c4bcbe12ad0c93f2eddd853f594989ca253296be1f41e1eb
> > Deleted: b6d05cd8d607423fdfab49bf4783c3065584f7b92baeeabf8e6f579072009670
> > Deleted: 0f4136cdbd63ffaa94a8a5a19dd85eb1ab708d97a82baf497fae00038c5e0438
> > Deleted: 6b926e415e33827e7b872a2bae8035cbe98da98ff6bc085aa9a65cb05bf85dec
> > Build step 'Execute shell' marked build as failure
> >
>
Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1955
Posted by Vinod Kone <vi...@apache.org>.
is this new?
On Thu, Apr 7, 2016 at 3:43 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1955/changes
> >
>
> Changes:
>
> [vinodkone] Fixed a memory leak in long lived executor.
>
> [vinodkone] Deleted the `run` method in long lived executor.
>
> [vinodkone] Cleaned up the virtual overloads in long lived executor.
>
> [vinodkone] Move long lived executor to use the v1 API.
>
> ------------------------------------------
> [...truncated 181353 lines...]
> I0407 22:43:33.535166 29299 master.hpp:1719] Sending heartbeat to
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> I0407 22:43:33.535372 29309 hierarchical.cpp:267] Added framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> I0407 22:43:33.535454 29309 hierarchical.cpp:1491] No resources available
> to allocate!
> I0407 22:43:33.535485 29309 hierarchical.cpp:1586] No inverse offers to
> send out!
> I0407 22:43:33.535538 29309 hierarchical.cpp:1142] Performed allocation
> for 0 agents in 157025ns
> I0407 22:43:33.537406 29303 scheduler.cpp:660] Enqueuing event SUBSCRIBED
> received from http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:33.538067 29303 scheduler.cpp:660] Enqueuing event HEARTBEAT received
> from http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:33.539325 29309 scheduler.cpp:234] Sending REQUEST call to
> http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:33.540393 29300 process.cpp:3165] Handling HTTP event for process
> 'master' with path: '/master/api/v1/scheduler'
> I0407 22:43:33.540752 29300 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:60090
> I0407 22:43:33.540972 29300 master.cpp:2801] Processing REQUEST call for
> framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> I0407 22:43:33.541285 29300 hierarchical.cpp:592] Received resource
> request from framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> I0407 22:43:33.544817 29296 master.cpp:1275] Framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) disconnected
> I0407 22:43:33.544844 29296 master.cpp:2658] Disconnecting framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> I0407 22:43:33.544867 29296 master.cpp:2682] Deactivating framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> I0407 22:43:33.544944 29296 master.cpp:1299] Giving framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default) 0ns to failover
> I0407 22:43:33.545146 29296 hierarchical.cpp:378] Deactivated framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> I0407 22:43:33.548176 29296 master.cpp:5376] Framework failover timeout,
> removing framework a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> I0407 22:43:33.548203 29296 master.cpp:6109] Removing framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000 (default)
> I0407 22:43:33.548461 29296 hierarchical.cpp:329] Removed framework
> a13d3c94-a4b9-4e08-badc-0ff16e8d3c0f-0000
> I0407 22:43:33.553081 29278 master.cpp:1089] Master terminating
> [ OK ] ContentType/SchedulerTest.Request/1 (468 ms)
> [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
> I0407 22:43:33.572032 29278 cluster.cpp:149] Creating default 'local'
> authorizer
> I0407 22:43:33.692395 29278 leveldb.cpp:174] Opened db in 119.97905ms
> I0407 22:43:33.717500 29278 leveldb.cpp:181] Compacted db in 25.05029ms
> I0407 22:43:33.717569 29278 leveldb.cpp:196] Created db iterator in 32981ns
> I0407 22:43:33.717587 29278 leveldb.cpp:202] Seeked to beginning of db in
> 9098ns
> I0407 22:43:33.717599 29278 leveldb.cpp:271] Iterated through 0 keys in
> the db in 7211ns
> I0407 22:43:33.717649 29278 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0407 22:43:33.718222 29298 recover.cpp:447] Starting replica recovery
> I0407 22:43:33.720281 29304 recover.cpp:473] Replica is in EMPTY status
> I0407 22:43:33.722905 29306 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (16658)@172.17.0.3:35855
> I0407 22:43:33.723548 29306 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0407 22:43:33.724474 29304 recover.cpp:564] Updating replica status to
> STARTING
> I0407 22:43:33.724983 29297 master.cpp:382] Master
> d2929ce6-157c-45b1-a02b-0b0264770ae3 (129e11060069) started on
> 172.17.0.3:35855
> I0407 22:43:33.725025 29297 master.cpp:384] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/OcAOGs/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_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/OcAOGs/master" --zk_session_timeout="10secs"
> I0407 22:43:33.725473 29297 master.cpp:435] Master allowing
> unauthenticated frameworks to register
> I0407 22:43:33.725487 29297 master.cpp:438] Master only allowing
> authenticated agents to register
> I0407 22:43:33.725497 29297 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/OcAOGs/credentials'
> I0407 22:43:33.725860 29297 master.cpp:480] Using default 'crammd5'
> authenticator
> I0407 22:43:33.726011 29297 master.cpp:551] Using default 'basic' HTTP
> authenticator
> I0407 22:43:33.726173 29297 master.cpp:589] Authorization enabled
> I0407 22:43:33.726353 29300 whitelist_watcher.cpp:77] No whitelist given
> I0407 22:43:33.726385 29311 hierarchical.cpp:145] Initialized hierarchical
> allocator process
> I0407 22:43:33.729537 29303 master.cpp:1832] The newly elected leader is
> master@172.17.0.3:35855 with id d2929ce6-157c-45b1-a02b-0b0264770ae3
> I0407 22:43:33.729670 29303 master.cpp:1845] Elected as the leading master!
> I0407 22:43:33.729773 29303 master.cpp:1532] Recovering from registrar
> I0407 22:43:33.730120 29303 registrar.cpp:331] Recovering registrar
> I0407 22:43:33.743185 29309 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 18.457836ms
> I0407 22:43:33.743227 29309 replica.cpp:320] Persisted replica status to
> STARTING
> I0407 22:43:33.743520 29304 recover.cpp:473] Replica is in STARTING status
> I0407 22:43:33.745204 29296 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16660)@172.17.0.3:35855
> I0407 22:43:33.745813 29300 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0407 22:43:33.746477 29309 recover.cpp:564] Updating replica status to
> VOTING
> I0407 22:43:33.767707 29309 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 20.960203ms
> I0407 22:43:33.767889 29309 replica.cpp:320] Persisted replica status to
> VOTING
> I0407 22:43:33.768352 29309 recover.cpp:578] Successfully joined the Paxos
> group
> I0407 22:43:33.768724 29309 recover.cpp:462] Recover process terminated
> I0407 22:43:33.769368 29296 log.cpp:659] Attempting to start the writer
> I0407 22:43:33.771579 29297 replica.cpp:493] Replica received implicit
> promise request from (16661)@172.17.0.3:35855 with proposal 1
> I0407 22:43:33.793212 29297 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.496269ms
> I0407 22:43:33.793275 29297 replica.cpp:342] Persisted promised to 1
> I0407 22:43:33.794962 29299 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0407 22:43:33.796274 29300 replica.cpp:388] Replica received explicit
> promise request from (16662)@172.17.0.3:35855 for position 0 with
> proposal 2
> I0407 22:43:33.831436 29300 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 35.137552ms
> I0407 22:43:33.831509 29300 replica.cpp:712] Persisted action at 0
> I0407 22:43:33.833217 29298 replica.cpp:537] Replica received write
> request for position 0 from (16663)@172.17.0.3:35855
> I0407 22:43:33.833304 29298 leveldb.cpp:436] Reading position from leveldb
> took 58613ns
> I0407 22:43:33.873253 29298 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 39.924326ms
> I0407 22:43:33.873325 29298 replica.cpp:712] Persisted action at 0
> I0407 22:43:33.874440 29307 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0407 22:43:33.915058 29307 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 40.580699ms
> I0407 22:43:33.915138 29307 replica.cpp:712] Persisted action at 0
> I0407 22:43:33.915179 29307 replica.cpp:697] Replica learned NOP action at
> position 0
> I0407 22:43:33.916415 29307 log.cpp:675] Writer started with ending
> position 0
> I0407 22:43:33.917920 29307 leveldb.cpp:436] Reading position from leveldb
> took 75769ns
> I0407 22:43:33.919996 29306 registrar.cpp:364] Successfully fetched the
> registry (0B) in 189.755904ms
> I0407 22:43:33.920181 29306 registrar.cpp:463] Applied 1 operations in
> 56218ns; attempting to update the 'registry'
> I0407 22:43:33.921454 29306 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0407 22:43:33.921658 29306 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0407 22:43:33.922703 29306 replica.cpp:537] Replica received write
> request for position 1 from (16664)@172.17.0.3:35855
> I0407 22:43:33.965260 29306 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 42.428505ms
> I0407 22:43:33.965317 29306 replica.cpp:712] Persisted action at 1
> I0407 22:43:33.966289 29302 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0407 22:43:34.006958 29302 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 40.595288ms
> I0407 22:43:34.007019 29302 replica.cpp:712] Persisted action at 1
> I0407 22:43:34.007047 29302 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0407 22:43:34.008319 29302 registrar.cpp:508] Successfully updated the
> 'registry' in 88.067072ms
> I0407 22:43:34.008491 29302 registrar.cpp:394] Successfully recovered
> registrar
> I0407 22:43:34.008616 29311 log.cpp:702] Attempting to truncate the log to
> 1
> I0407 22:43:34.008780 29302 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0407 22:43:34.008955 29311 master.cpp:1640] Recovered 0 agents from the
> Registry (131B) ; allowing 10mins for agents to re-register
> I0407 22:43:34.009169 29311 hierarchical.cpp:172] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0407 22:43:34.009928 29298 replica.cpp:537] Replica received write
> request for position 2 from (16665)@172.17.0.3:35855
> I0407 22:43:34.057065 29298 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 47.115137ms
> I0407 22:43:34.057111 29298 replica.cpp:712] Persisted action at 2
> I0407 22:43:34.058159 29298 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0407 22:43:34.098841 29298 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 40.667512ms
> I0407 22:43:34.098918 29298 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 56150ns
> I0407 22:43:34.098948 29298 replica.cpp:712] Persisted action at 2
> I0407 22:43:34.098974 29298 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0407 22:43:34.100977 29278 scheduler.cpp:175] Version: 0.29.0
> I0407 22:43:34.101480 29310 scheduler.cpp:469] New master detected at
> master@172.17.0.3:35855
> I0407 22:43:34.104208 29302 scheduler.cpp:358] Connected with the master
> at http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:34.106226 29299 scheduler.cpp:440] Re-detecting master
> I0407 22:43:34.106591 29299 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.106703 29299 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.106921 29299 scheduler.cpp:469] New master detected at
> master@172.17.0.3:35855
> I0407 22:43:34.109439 29305 scheduler.cpp:358] Connected with the master
> at http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407> 22:43:34.120393
> 29304 scheduler.cpp:444] Lost leading master
> I0407 22:43:34.128222 29304 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.128386 29304 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.128876 29297 scheduler.cpp:278] Ignoring reconnect request
> from scheduler since we are disconnected
> I0407 22:43:34.149577 29278 master.cpp:1089] Master terminating
> [ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (589 ms)
> [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
> I0407 22:43:34.160708 29278 cluster.cpp:149] Creating default 'local'
> authorizer
> I0407 22:43:34.284922 29278 leveldb.cpp:174] Opened db in 124.01605ms
> I0407 22:43:34.318502 29278 leveldb.cpp:181] Compacted db in 33.548132ms
> I0407 22:43:34.318598 29278 leveldb.cpp:196] Created db iterator in 32743ns
> I0407 22:43:34.318614 29278 leveldb.cpp:202] Seeked to beginning of db in
> 8438ns
> I0407 22:43:34.318626 29278 leveldb.cpp:271] Iterated through 0 keys in
> the db in 7692ns
> I0407 22:43:34.318675 29278 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0407 22:43:34.319649 29298 recover.cpp:447] Starting replica recovery
> I0407 22:43:34.320418 29297 recover.cpp:473] Replica is in EMPTY status
> I0407 22:43:34.322320 29309 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (16669)@172.17.0.3:35855
> I0407 22:43:34.322985 29309 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0407 22:43:34.324254 29303 recover.cpp:564] Updating replica status to
> STARTING
> I0407 22:43:34.325510 29307 master.cpp:382] Master
> 9ec88334-b056-4673-930e-074355a030ef (129e11060069) started on
> 172.17.0.3:35855
> I0407 22:43:34.325820 29307 master.cpp:384] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/TqtB8N/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_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/TqtB8N/master" --zk_session_timeout="10secs"
> I0407 22:43:34.326524 29307 master.cpp:435] Master allowing
> unauthenticated frameworks to register
> I0407 22:43:34.326637 29307 master.cpp:438] Master only allowing
> authenticated agents to register
> I0407 22:43:34.326745 29307 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/TqtB8N/credentials'
> I0407 22:43:34.327327 29307 master.cpp:480] Using default 'crammd5'
> authenticator
> I0407 22:43:34.327642 29307 master.cpp:551] Using default 'basic' HTTP
> authenticator
> I0407 22:43:34.327967 29307 master.cpp:589] Authorization enabled
> I0407 22:43:34.328367 29302 hierarchical.cpp:145] Initialized hierarchical
> allocator process
> I0407 22:43:34.328435 29302 whitelist_watcher.cpp:77] No whitelist given
> I0407 22:43:34.330946 29299 master.cpp:1832] The newly elected leader is
> master@172.17.0.3:35855 with id 9ec88334-b056-4673-930e-074355a030ef
> I0407 22:43:34.330986 29299 master.cpp:1845] Elected as the leading master!
> I0407 22:43:34.331001 29299 master.cpp:1532] Recovering from registrar
> I0407 22:43:34.331282 29299 registrar.cpp:331] Recovering registrar
> I0407 22:43:34.351889 29301 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 27.449575ms
> I0407 22:43:34.351950 29301 replica.cpp:320] Persisted replica status to
> STARTING
> I0407 22:43:34.352294 29301 recover.cpp:473] Replica is in STARTING status
> I0407 22:43:34.354171 29301 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16671)@172.17.0.3:35855
> I0407 22:43:34.354565 29301 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0407 22:43:34.355317 29308 recover.cpp:564] Updating replica status to
> VOTING
> I0407 22:43:34.377023 29308 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.36009ms
> I0407 22:43:34.377225 29308 replica.cpp:320] Persisted replica status to
> VOTING
> I0407 22:43:34.377491 29308 recover.cpp:578] Successfully joined the Paxos
> group
> I0407 22:43:34.377691 29308 recover.cpp:462] Recover process terminated
> I0407 22:43:34.378443 29308 log.cpp:659] Attempting to start the writer
> I0407 22:43:34.380103 29309 replica.cpp:493] Replica received implicit
> promise request from (16672)@172.17.0.3:35855 with proposal 1
> I0407 22:43:34.393790 29309 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 13.658222ms
> I0407 22:43:34.393851 29309 replica.cpp:342] Persisted promised to 1
> I0407 22:43:34.394953 29302 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0407 22:43:34.396569 29297 replica.cpp:388] Replica received explicit
> promise request from (16673)@172.17.0.3:35855 for position 0 with
> proposal 2
> I0407 22:43:34.410588 29297 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 13.99384ms
> I0407 22:43:34.410648 29297 replica.cpp:712] Persisted action at 0
> I0407 22:43:34.412385 29306 replica.cpp:537] Replica received write
> request for position 0 from (16674)@172.17.0.3:35855
> I0407 22:43:34.412509 29306 leveldb.cpp:436] Reading position from leveldb
> took 66530ns
> I0407 22:43:34.427356 29306 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 14.71772ms
> I0407 22:43:34.427525 29306 replica.cpp:712] Persisted action at 0
> I0407 22:43:34.428591 29298 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0407 22:43:34.444162 29298 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 15.464087ms
> I0407 22:43:34.444386 29298 replica.cpp:712] Persisted action at 0
> I0407 22:43:34.444563 29298 replica.cpp:697] Replica learned NOP action at
> position 0
> I0407 22:43:34.445961 29298 log.cpp:675] Writer started with ending
> position 0
> I0407 22:43:34.448225 29298 leveldb.cpp:436] Reading position from leveldb
> took 72324ns
> I0407 22:43:34.449959 29301 registrar.cpp:364] Successfully fetched the
> registry (0B) in 118624us
> I0407 22:43:34.450192 29301 registrar.cpp:463] Applied 1 operations in
> 141125ns; attempting to update the 'registry'
> I0407 22:43:34.451187 29311 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0407 22:43:34.451658 29301 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0407 22:43:34.452664 29306 replica.cpp:537] Replica received write
> request for position 1 from (16675)@172.17.0.3:35855
> I0407 22:43:34.469269 29306 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 16.580714ms
> I0407 22:43:34.469333 29306 replica.cpp:712] Persisted action at 1
> I0407 22:43:34.470687 29306 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0407 22:43:34.486049 29306 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 15.347979ms
> I0407 22:43:34.486106 29306 replica.cpp:712] Persisted action at 1
> I0407 22:43:34.486160 29306 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0407 22:43:34.488042 29307 registrar.cpp:508] Successfully updated the
> 'registry' in 37.76384ms
> I0407 22:43:34.488186 29296 log.cpp:702] Attempting to truncate the log to
> 1
> I0407 22:43:34.488824 29299 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0407 22:43:34.490442 29299 replica.cpp:537] Replica received write
> request for position 2 from (16676)@172.17.0.3:35855
> I0407 22:43:34.491020 29307 registrar.cpp:394] Successfully recovered
> registrar
> I0407 22:43:34.491515 29307 master.cpp:1640] Recovered 0 agents from the
> Registry (131B) ; allowing 10mins for agents to re-register
> I0407 22:43:34.491685 29303 hierarchical.cpp:172] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0407 22:43:34.511176 29299 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 20.372117ms
> I0407 22:43:34.511369 29299 replica.cpp:712] Persisted action at 2
> I0407 22:43:34.513206 29299 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0407 22:43:34.536279 29299 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.060111ms
> I0407 22:43:34.536406 29299 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 77707ns
> I0407 22:43:34.536557 29299 replica.cpp:712] Persisted action at 2
> I0407 22:43:34.536694 29299 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0407 22:43:34.545011 29278 scheduler.cpp:175] Version: 0.29.0
> I0407 22:43:34.545533 29311 scheduler.cpp:469] New master detected at
> master@172.17.0.3:35855
> I0407 22:43:34.548821 29298 scheduler.cpp:358] Connected with the master
> at http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:34.551250 29309 scheduler.cpp:440] Re-detecting master
> I0407 22:43:34.551614 29309 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.551749 29309 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.552273 29309 scheduler.cpp:469] New master detected at
> master@172.17.0.3:35855
> I0407 22:43:34.554633 29306 scheduler.cpp:358] Connected with the master
> at http://172.17.0.3:35855/master/api/v1/scheduler
> I0407 <http://172.17.0.3:35855/master/api/v1/schedulerI0407>
> 22:43:34.556355 29307 scheduler.cpp:444] Lost leading master
> I0407 22:43:34.556821 29307 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.556948 29307 scheduler.cpp:391] Ignoring disconnection
> attempt from stale connection
> I0407 22:43:34.558166 29307 scheduler.cpp:278] Ignoring reconnect request
> from scheduler since we are disconnected
> I0407 22:43:34.582536 29278 master.cpp:1089] Master terminating
> [ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (437 ms)
> [----------] 28 tests from ContentType/SchedulerTest (22152 ms total)
>
> [----------] Global test environment tear-down
> [==========] 1059 tests from 130 test cases ran. (849386 ms total)
> [ PASSED ] 1058 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] MasterAllocatorTest/1.RebalancedForUpdatedWeights, where
> TypeParam =
> mesos::internal::tests::Module<mesos::master::allocator::Allocator,
> (mesos::internal::tests::ModuleID)6>
>
> 1 FAILED TEST
> YOU HAVE 6 DISABLED TESTS
>
> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[4]: *** [check-local] Error 1
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[2]: *** [check] Error 2
> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make[1]: *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1460066014-22985
> Untagged: mesos-1460066014-22985:latest
> Deleted: 90940acb7da9218a3dce5c512a16554bbc3001c458feb3462def9caf3570f778
> Deleted: a8b0f613c0d1f6bc75bde2d2b173fd072083c692fdb856dcce4d8825ba061395
> Deleted: 48ca606dff75e538ce888c8f1a0aeb37c954f9fb0ce7976bb15509899c1f520c
> Deleted: c631b92c92b447da71d74f05835b04616e89650c790f8c974b971d2405b88fa1
> Deleted: c6bf725d00b56d54a409b0637078659bbf35d777d48fdeeea99990ebbefb6cb7
> Deleted: c741b5dfdb46affc3a32d4dabc0434b2ed233208a87f58f66dfe13d63dd82df7
> Deleted: b39d2acfa97ab056ad3bf37a9fbd2f14625400b27652694f8f1b2c493d0006f1
> Deleted: 722eb2749b09109e8ceade9c37929a5c71679d746b366b052fdf10b365abf7bd
> Deleted: e2790626988ff17c2979a75626a00f633b472c892bb43b7ad330222d6ef2b910
> Deleted: d8984a410c448ee4f414280b422c4aa3789821b2a3b4a57c53a8804001bf1cf0
> Deleted: bba6521cb2c41d4fe5439858b1ac50240e1e090b16d570a88fc6d1ba0bdfe1d6
> Deleted: e7b0ec4e4ce154529b3b5e0f9bec73072b69cca1611f0e7c7ff05c5616151706
> Deleted: dd832db027d923c8c4bcbe12ad0c93f2eddd853f594989ca253296be1f41e1eb
> Deleted: b6d05cd8d607423fdfab49bf4783c3065584f7b92baeeabf8e6f579072009670
> Deleted: 0f4136cdbd63ffaa94a8a5a19dd85eb1ab708d97a82baf497fae00038c5e0438
> Deleted: 6b926e415e33827e7b872a2bae8035cbe98da98ff6bc085aa9a65cb05bf85dec
> Build step 'Execute shell' marked build as failure
>
Jenkins build is back to normal : Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1956
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1956/changes>