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/03/21 11:52:13 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1837
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1837/changes>
Changes:
[joris.van.remoortere] Fixed email address in Python build file.
------------------------------------------
[...truncated 172170 lines...]
I0321 10:52:09.538982 1260 replica.cpp:320] Persisted replica status to STARTING
I0321 10:52:09.539014 1261 master.cpp:376] Master 38d659fb-738e-42e0-8c40-874ef3d69231 (fd5495f8511c) started on 172.17.0.1:60994
I0321 10:52:09.539243 1268 recover.cpp:473] Replica is in STARTING status
I0321 10:52:09.539034 1261 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/GvPC7b/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/GvPC7b/master" --zk_session_timeout="10secs"
I0321 10:52:09.539451 1261 master.cpp:425] Master allowing unauthenticated frameworks to register
I0321 10:52:09.539466 1261 master.cpp:428] Master only allowing authenticated slaves to register
I0321 10:52:09.539475 1261 credentials.hpp:35] Loading credentials for authentication from '/tmp/GvPC7b/credentials'
I0321 10:52:09.539902 1261 master.cpp:468] Using default 'crammd5' authenticator
I0321 10:52:09.540083 1261 master.cpp:537] Using default 'basic' HTTP authenticator
I0321 10:52:09.540242 1261 master.cpp:571] Authorization enabled
I0321 10:52:09.540316 1267 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15608)@172.17.0.1:60994
I0321 10:52:09.540647 1256 hierarchical.cpp:144] Initialized hierarchical allocator process
I0321 10:52:09.540925 1262 whitelist_watcher.cpp:77] No whitelist given
I0321 10:52:09.541853 1255 recover.cpp:193] Received a recover response from a replica in STARTING status
I0321 10:52:09.542304 1267 recover.cpp:564] Updating replica status to VOTING
I0321 10:52:09.542960 1268 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 505870ns
I0321 10:52:09.542997 1268 replica.cpp:320] Persisted replica status to VOTING
I0321 10:52:09.543128 1268 recover.cpp:578] Successfully joined the Paxos group
I0321 10:52:09.543392 1268 recover.cpp:462] Recover process terminated
I0321 10:52:09.544442 1264 master.cpp:1806] The newly elected leader is master@172.17.0.1:60994 with id 38d659fb-738e-42e0-8c40-874ef3d69231
I0321 10:52:09.544484 1264 master.cpp:1819] Elected as the leading master!
I0321 10:52:09.544507 1264 master.cpp:1508] Recovering from registrar
I0321 10:52:09.544642 1268 registrar.cpp:307] Recovering registrar
I0321 10:52:09.545301 1257 log.cpp:659] Attempting to start the writer
I0321 10:52:09.546573 1263 replica.cpp:493] Replica received implicit promise request from (15610)@172.17.0.1:60994 with proposal 1
I0321 10:52:09.547096 1263 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 484462ns
I0321 10:52:09.547118 1263 replica.cpp:342] Persisted promised to 1
I0321 10:52:09.547693 1266 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0321 10:52:09.548806 1266 replica.cpp:388] Replica received explicit promise request from (15611)@172.17.0.1:60994 for position 0 with proposal 2
I0321 10:52:09.549202 1266 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 343322ns
I0321 10:52:09.549226 1266 replica.cpp:712] Persisted action at 0
I0321 10:52:09.550324 1269 replica.cpp:537] Replica received write request for position 0 from (15612)@172.17.0.1:60994
I0321 10:52:09.550392 1269 leveldb.cpp:436] Reading position from leveldb took 33661ns
I0321 10:52:09.550925 1269 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 489538ns
I0321 10:52:09.550952 1269 replica.cpp:712] Persisted action at 0
I0321 10:52:09.551643 1261 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0321 10:52:09.552139 1261 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 394478ns
I0321 10:52:09.552161 1261 replica.cpp:712] Persisted action at 0
I0321 10:52:09.552183 1261 replica.cpp:697] Replica learned NOP action at position 0
I0321 10:52:09.552834 1263 log.cpp:675] Writer started with ending position 0
I0321 10:52:09.554002 1266 leveldb.cpp:436] Reading position from leveldb took 35244ns
I0321 10:52:09.555251 1258 registrar.cpp:340] Successfully fetched the registry (0B) in 10.5408ms
I0321 10:52:09.555443 1258 registrar.cpp:439] Applied 1 operations in 40786ns; attempting to update the 'registry'
I0321 10:52:09.556310 1266 log.cpp:683] Attempting to append 170 bytes to the log
I0321 10:52:09.556468 1257 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0321 10:52:09.557317 1266 replica.cpp:537] Replica received write request for position 1 from (15613)@172.17.0.1:60994
I0321 10:52:09.557886 1266 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 520847ns
I0321 10:52:09.557915 1266 replica.cpp:712] Persisted action at 1
I0321 10:52:09.558594 1270 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0321 10:52:09.559003 1270 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 379962ns
I0321 10:52:09.559027 1270 replica.cpp:712] Persisted action at 1
I0321 10:52:09.559046 1270 replica.cpp:697] Replica learned APPEND action at position 1
I0321 10:52:09.560156 1262 registrar.cpp:484] Successfully updated the 'registry' in 4.647936ms
I0321 10:52:09.560304 1262 registrar.cpp:370] Successfully recovered registrar
I0321 10:52:09.560451 1269 log.cpp:702] Attempting to truncate the log to 1
I0321 10:52:09.560770 1268 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0321 10:52:09.560811 1256 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0321 10:52:09.560845 1270 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0321 10:52:09.561681 1268 replica.cpp:537] Replica received write request for position 2 from (15614)@172.17.0.1:60994
I0321 10:52:09.562150 1268 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 424626ns
I0321 10:52:09.562173 1268 replica.cpp:712] Persisted action at 2
I0321 10:52:09.563274 1270 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0321 10:52:09.563773 1270 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 444555ns
I0321 10:52:09.563850 1270 leveldb.cpp:399] Deleting ~1 keys from leveldb took 49506ns
I0321 10:52:09.563871 1270 replica.cpp:712] Persisted action at 2
I0321 10:52:09.563899 1270 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0321 10:52:09.571946 1236 scheduler.cpp:172] Version: 0.29.0
I0321 10:52:09.572803 1270 scheduler.cpp:437] New master detected at master@172.17.0.1:60994
I0321 10:52:09.574466 1261 scheduler.cpp:337] Connected with the master at http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.575734 1270 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.576658 1260 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0321 10:52:09.577170 1270 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:32956
I0321 10:52:09.577366 1270 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0321 10:52:09.577431 1270 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0321 10:52:09.577723 1263 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0321 10:52:09.578204 1264 hierarchical.cpp:265] Added framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000
I0321 10:52:09.578272 1263 master.hpp:1715] Sending heartbeat to 38d659fb-738e-42e0-8c40-874ef3d69231-0000
I0321 10:52:09.578333 1264 hierarchical.cpp:1453] No resources available to allocate!
I0321 10:52:09.578375 1264 hierarchical.cpp:1548] No inverse offers to send out!
I0321 10:52:09.578423 1264 hierarchical.cpp:1130] Performed allocation for 0 slaves in 189377ns
I0321 10:52:09.579202 1258 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.579733 1258 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.580601 1258 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.581527 1255 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0321 10:52:09.581980 1270 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:32957
I0321 10:52:09.582120 1270 master.cpp:2765] Processing REQUEST call for framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
I0321 10:52:09.582267 1267 hierarchical.cpp:589] Received resource request from framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000
I0321 10:52:09.584018 1255 master.cpp:1251] Framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default) disconnected
I0321 10:52:09.584060 1255 master.cpp:2622] Disconnecting framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
I0321 10:52:09.584084 1255 master.cpp:2646] Deactivating framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
I0321 10:52:09.584233 1255 master.cpp:1275] Giving framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default) 0ns to failover
I0321 10:52:09.584280 1266 hierarchical.cpp:375] Deactivated framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000
I0321 10:52:09.585660 1262 master.cpp:5340] Framework failover timeout, removing framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
I0321 10:52:09.585691 1262 master.cpp:6073] Removing framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
I0321 10:52:09.586012 1266 hierarchical.cpp:326] Removed framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000
I0321 10:52:09.594507 1269 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/0 (72 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0321 10:52:09.602182 1236 cluster.cpp:139] Creating default 'local' authorizer
I0321 10:52:09.605136 1236 leveldb.cpp:174] Opened db in 2.70234ms
I0321 10:52:09.606045 1236 leveldb.cpp:181] Compacted db in 854648ns
I0321 10:52:09.606101 1236 leveldb.cpp:196] Created db iterator in 22950ns
I0321 10:52:09.606117 1236 leveldb.cpp:202] Seeked to beginning of db in 1987ns
I0321 10:52:09.606127 1236 leveldb.cpp:271] Iterated through 0 keys in the db in 287ns
I0321 10:52:09.606189 1236 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0321 10:52:09.606730 1258 recover.cpp:447] Starting replica recovery
I0321 10:52:09.607046 1258 recover.cpp:473] Replica is in EMPTY status
I0321 10:52:09.608391 1267 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15622)@172.17.0.1:60994
I0321 10:52:09.608903 1256 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0321 10:52:09.609521 1269 recover.cpp:564] Updating replica status to STARTING
I0321 10:52:09.609977 1268 master.cpp:376] Master a294aafa-2994-4380-a055-1657ad4aeb1a (fd5495f8511c) started on 172.17.0.1:60994
I0321 10:52:09.610002 1268 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/uKqaD0/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/uKqaD0/master" --zk_session_timeout="10secs"
I0321 10:52:09.610345 1259 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 628418ns
I0321 10:52:09.610368 1268 master.cpp:425] Master allowing unauthenticated frameworks to register
I0321 10:52:09.610373 1259 replica.cpp:320] Persisted replica status to STARTING
I0321 10:52:09.610384 1268 master.cpp:428] Master only allowing authenticated slaves to register
I0321 10:52:09.610414 1268 credentials.hpp:35] Loading credentials for authentication from '/tmp/uKqaD0/credentials'
I0321 10:52:09.610621 1264 recover.cpp:473] Replica is in STARTING status
I0321 10:52:09.610782 1268 master.cpp:468] Using default 'crammd5' authenticator
I0321 10:52:09.610965 1268 master.cpp:537] Using default 'basic' HTTP authenticator
I0321 10:52:09.611137 1268 master.cpp:571] Authorization enabled
I0321 10:52:09.611321 1260 whitelist_watcher.cpp:77] No whitelist given
I0321 10:52:09.611343 1257 hierarchical.cpp:144] Initialized hierarchical allocator process
I0321 10:52:09.611670 1265 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15624)@172.17.0.1:60994
I0321 10:52:09.612175 1270 recover.cpp:193] Received a recover response from a replica in STARTING status
I0321 10:52:09.612669 1263 recover.cpp:564] Updating replica status to VOTING
I0321 10:52:09.613292 1260 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 475384ns
I0321 10:52:09.613322 1260 replica.cpp:320] Persisted replica status to VOTING
I0321 10:52:09.613492 1260 recover.cpp:578] Successfully joined the Paxos group
I0321 10:52:09.613706 1260 recover.cpp:462] Recover process terminated
I0321 10:52:09.614096 1269 master.cpp:1806] The newly elected leader is master@172.17.0.1:60994 with id a294aafa-2994-4380-a055-1657ad4aeb1a
I0321 10:52:09.614125 1269 master.cpp:1819] Elected as the leading master!
I0321 10:52:09.614140 1269 master.cpp:1508] Recovering from registrar
I0321 10:52:09.614280 1258 registrar.cpp:307] Recovering registrar
I0321 10:52:09.614900 1261 log.cpp:659] Attempting to start the writer
I0321 10:52:09.616181 1261 replica.cpp:493] Replica received implicit promise request from (15625)@172.17.0.1:60994 with proposal 1
I0321 10:52:09.616613 1261 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 387232ns
I0321 10:52:09.616638 1261 replica.cpp:342] Persisted promised to 1
I0321 10:52:09.617393 1257 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0321 10:52:09.618605 1255 replica.cpp:388] Replica received explicit promise request from (15626)@172.17.0.1:60994 for position 0 with proposal 2
I0321 10:52:09.619025 1255 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 373888ns
I0321 10:52:09.619050 1255 replica.cpp:712] Persisted action at 0
I0321 10:52:09.620128 1262 replica.cpp:537] Replica received write request for position 0 from (15627)@172.17.0.1:60994
I0321 10:52:09.620220 1262 leveldb.cpp:436] Reading position from leveldb took 42983ns
I0321 10:52:09.620625 1262 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 345487ns
I0321 10:52:09.620648 1262 replica.cpp:712] Persisted action at 0
I0321 10:52:09.621266 1262 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0321 10:52:09.621619 1262 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 319952ns
I0321 10:52:09.621649 1262 replica.cpp:712] Persisted action at 0
I0321 10:52:09.621676 1262 replica.cpp:697] Replica learned NOP action at position 0
I0321 10:52:09.622372 1269 log.cpp:675] Writer started with ending position 0
I0321 10:52:09.623651 1260 leveldb.cpp:436] Reading position from leveldb took 56734ns
I0321 10:52:09.624791 1269 registrar.cpp:340] Successfully fetched the registry (0B) in 10.445824ms
I0321 10:52:09.624966 1269 registrar.cpp:439] Applied 1 operations in 37159ns; attempting to update the 'registry'
I0321 10:52:09.625689 1264 log.cpp:683] Attempting to append 170 bytes to the log
I0321 10:52:09.625845 1256 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0321 10:52:09.626632 1264 replica.cpp:537] Replica received write request for position 1 from (15628)@172.17.0.1:60994
I0321 10:52:09.627161 1264 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 482207ns
I0321 10:52:09.627188 1264 replica.cpp:712] Persisted action at 1
I0321 10:52:09.627841 1261 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0321 10:52:09.628232 1261 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 345343ns
I0321 10:52:09.628258 1261 replica.cpp:712] Persisted action at 1
I0321 10:52:09.628286 1261 replica.cpp:697] Replica learned APPEND action at position 1
I0321 10:52:09.629423 1257 registrar.cpp:484] Successfully updated the 'registry' in 4.388096ms
I0321 10:52:09.629590 1257 registrar.cpp:370] Successfully recovered registrar
I0321 10:52:09.629726 1255 log.cpp:702] Attempting to truncate the log to 1
I0321 10:52:09.629899 1259 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0321 10:52:09.630347 1255 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0321 10:52:09.630353 1269 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0321 10:52:09.631206 1270 replica.cpp:537] Replica received write request for position 2 from (15629)@172.17.0.1:60994
I0321 10:52:09.631649 1270 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 398171ns
I0321 10:52:09.631674 1270 replica.cpp:712] Persisted action at 2
I0321 10:52:09.632382 1259 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0321 10:52:09.632740 1259 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 325356ns
I0321 10:52:09.632799 1259 leveldb.cpp:399] Deleting ~1 keys from leveldb took 33421ns
I0321 10:52:09.632853 1259 replica.cpp:712] Persisted action at 2
I0321 10:52:09.632886 1259 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0321 10:52:09.641461 1236 scheduler.cpp:172] Version: 0.29.0
I0321 10:52:09.642503 1270 scheduler.cpp:437] New master detected at master@172.17.0.1:60994
I0321 10:52:09.644632 1269 scheduler.cpp:337] Connected with the master at http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.646297 1263 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.647776 1263 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0321 10:52:09.648418 1268 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:32958
I0321 10:52:09.648921 1268 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0321 10:52:09.649008 1268 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0321 10:52:09.649320 1268 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0321 10:52:09.649912 1263 hierarchical.cpp:265] Added framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000
I0321 10:52:09.650022 1263 hierarchical.cpp:1453] No resources available to allocate!
I0321 10:52:09.650068 1263 hierarchical.cpp:1548] No inverse offers to send out!
I0321 10:52:09.650096 1263 hierarchical.cpp:1130] Performed allocation for 0 slaves in 154326ns
I0321 10:52:09.650126 1263 master.hpp:1715] Sending heartbeat to a294aafa-2994-4380-a055-1657ad4aeb1a-0000
I0321 10:52:09.651443 1261 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.652222 1261 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.653123 1267 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.1:60994/master/api/v1/scheduler
I0321 10:52:09.654463 1267 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0321 10:52:09.654955 1268 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:32959
I0321 10:52:09.655293 1268 master.cpp:2765] Processing REQUEST call for framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
I0321 10:52:09.655441 1257 hierarchical.cpp:589] Received resource request from framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000
I0321 10:52:09.659122 1265 master.cpp:1251] Framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default) disconnected
I0321 10:52:09.659273 1265 master.cpp:2622] Disconnecting framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
I0321 10:52:09.659454 1265 master.cpp:2646] Deactivating framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
I0321 10:52:09.659667 1265 master.cpp:1275] Giving framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default) 0ns to failover
I0321 10:52:09.660125 1266 hierarchical.cpp:375] Deactivated framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000
I0321 10:52:09.661260 1263 master.cpp:5340] Framework failover timeout, removing framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
I0321 10:52:09.661294 1263 master.cpp:6073] Removing framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
I0321 10:52:09.661581 1263 hierarchical.cpp:326] Removed framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000
I0321 10:52:09.668884 1259 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (75 ms)
[----------] 26 tests from ContentType/SchedulerTest (5391 ms total)
[----------] Global test environment tear-down
E0321 10:52:09.939385 1236 environment.cpp:752] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_B9MRuR': No such file or directory
E0321 10:52:09.939470 1236 environment.cpp:752] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_8JY8hc': No such file or directory
[==========] 1030 tests from 131 test cases ran. (411027 ms total)
[ PASSED ] 1029 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1458555109-3729
Untagged: mesos-1458555109-3729:latest
Deleted: 64cc3cc53b165b10a15a98c4d0caaccaf5422e5dfb8fe3d4f4739a611fa7b6d0
Deleted: 49436ae04da62b452e3317a30cf23a9392b8b399cff1b6dd3860e6c3108fa2c4
Deleted: 4ee5fd24df872d346adabc13c0aa2f03c0d65688d0e2e96f936ac91f7e846b03
Deleted: 07193b1951fe61d233f64bd237b53e4ee6edbe42e04d0b821fc379d74c6ee331
Deleted: bc477332c6b27c0b4a949e0c81399ca03a7836353cc44178ab4aacb6e5283ced
Deleted: 3c8e4dde9f57a9043f0d54717b3044bd2d5760ce7b48a598f81bae31ed490855
Deleted: 995c15c3e0a4ba7b26624eb5cefb09e6c68a244d40328b663fd91eac320b4bc4
Deleted: 2672eb5a58665422e69c618888c3f8ebb59378b9554c4f64664aa704fbb5435f
Deleted: 062c6fc21a6f45e5d2eb5308fc766f68f6ac8dd72ca1740102f6d013fa3603a7
Deleted: 102494963b7120b55d1a35ea45896da6e77636c24d0f9ddac434cd3f174092b2
Deleted: e14ae29b7b1a6ce71abc6a340c262ab767ef421f0df2e9ae155dc6d1821629e4
Deleted: 9c1b585c7b49eb563ca749cda9db74abf27b5cdcff4da8556c58d0bcc64d960b
Deleted: 7e89680c9f979ce2d858654e3c3f9535db3a380f6329965bc4af3ef145822530
Deleted: 47221266a8ae34f02f47ea45a88c0bc4d755cbcef8e2e51f76fcc6408de299bd
Deleted: 1966214245d7956fdae8de1cd2ec96889c7fbee6c5212fda33e921210c8ccc3c
Deleted: 2ceecc0c6b86e66dd503dcdfc6561c6d7b93a352583eff884217b5c26b651b74
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1838
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1838/changes>
Re: Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1837
Posted by Joris Van Remoortere <jo...@mesosphere.io>.
This is MESOS-4961 <https://issues.apache.org/jira/browse/MESOS-4961>
—
*Joris Van Remoortere*
Mesosphere
On Mon, Mar 21, 2016 at 3:52 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1837/changes
> >
>
> Changes:
>
> [joris.van.remoortere] Fixed email address in Python build file.
>
> ------------------------------------------
> [...truncated 172170 lines...]
> I0321 10:52:09.538982 1260 replica.cpp:320] Persisted replica status to
> STARTING
> I0321 10:52:09.539014 1261 master.cpp:376] Master
> 38d659fb-738e-42e0-8c40-874ef3d69231 (fd5495f8511c) started on
> 172.17.0.1:60994
> I0321 10:52:09.539243 1268 recover.cpp:473] Replica is in STARTING status
> I0321 10:52:09.539034 1261 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/GvPC7b/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/GvPC7b/master" --zk_session_timeout="10secs"
> I0321 10:52:09.539451 1261 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0321 10:52:09.539466 1261 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0321 10:52:09.539475 1261 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/GvPC7b/credentials'
> I0321 10:52:09.539902 1261 master.cpp:468] Using default 'crammd5'
> authenticator
> I0321 10:52:09.540083 1261 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0321 10:52:09.540242 1261 master.cpp:571] Authorization enabled
> I0321 10:52:09.540316 1267 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (15608)@172.17.0.1:60994
> I0321 10:52:09.540647 1256 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0321 10:52:09.540925 1262 whitelist_watcher.cpp:77] No whitelist given
> I0321 10:52:09.541853 1255 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0321 10:52:09.542304 1267 recover.cpp:564] Updating replica status to
> VOTING
> I0321 10:52:09.542960 1268 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 505870ns
> I0321 10:52:09.542997 1268 replica.cpp:320] Persisted replica status to
> VOTING
> I0321 10:52:09.543128 1268 recover.cpp:578] Successfully joined the Paxos
> group
> I0321 10:52:09.543392 1268 recover.cpp:462] Recover process terminated
> I0321 10:52:09.544442 1264 master.cpp:1806] The newly elected leader is
> master@172.17.0.1:60994 with id 38d659fb-738e-42e0-8c40-874ef3d69231
> I0321 10:52:09.544484 1264 master.cpp:1819] Elected as the leading master!
> I0321 10:52:09.544507 1264 master.cpp:1508] Recovering from registrar
> I0321 10:52:09.544642 1268 registrar.cpp:307] Recovering registrar
> I0321 10:52:09.545301 1257 log.cpp:659] Attempting to start the writer
> I0321 10:52:09.546573 1263 replica.cpp:493] Replica received implicit
> promise request from (15610)@172.17.0.1:60994 with proposal 1
> I0321 10:52:09.547096 1263 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 484462ns
> I0321 10:52:09.547118 1263 replica.cpp:342] Persisted promised to 1
> I0321 10:52:09.547693 1266 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0321 10:52:09.548806 1266 replica.cpp:388] Replica received explicit
> promise request from (15611)@172.17.0.1:60994 for position 0 with
> proposal 2
> I0321 10:52:09.549202 1266 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 343322ns
> I0321 10:52:09.549226 1266 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.550324 1269 replica.cpp:537] Replica received write
> request for position 0 from (15612)@172.17.0.1:60994
> I0321 10:52:09.550392 1269 leveldb.cpp:436] Reading position from leveldb
> took 33661ns
> I0321 10:52:09.550925 1269 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 489538ns
> I0321 10:52:09.550952 1269 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.551643 1261 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0321 10:52:09.552139 1261 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 394478ns
> I0321 10:52:09.552161 1261 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.552183 1261 replica.cpp:697] Replica learned NOP action at
> position 0
> I0321 10:52:09.552834 1263 log.cpp:675] Writer started with ending
> position 0
> I0321 10:52:09.554002 1266 leveldb.cpp:436] Reading position from leveldb
> took 35244ns
> I0321 10:52:09.555251 1258 registrar.cpp:340] Successfully fetched the
> registry (0B) in 10.5408ms
> I0321 10:52:09.555443 1258 registrar.cpp:439] Applied 1 operations in
> 40786ns; attempting to update the 'registry'
> I0321 10:52:09.556310 1266 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0321 10:52:09.556468 1257 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0321 10:52:09.557317 1266 replica.cpp:537] Replica received write
> request for position 1 from (15613)@172.17.0.1:60994
> I0321 10:52:09.557886 1266 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 520847ns
> I0321 10:52:09.557915 1266 replica.cpp:712] Persisted action at 1
> I0321 10:52:09.558594 1270 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0321 10:52:09.559003 1270 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 379962ns
> I0321 10:52:09.559027 1270 replica.cpp:712] Persisted action at 1
> I0321 10:52:09.559046 1270 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0321 10:52:09.560156 1262 registrar.cpp:484] Successfully updated the
> 'registry' in 4.647936ms
> I0321 10:52:09.560304 1262 registrar.cpp:370] Successfully recovered
> registrar
> I0321 10:52:09.560451 1269 log.cpp:702] Attempting to truncate the log to
> 1
> I0321 10:52:09.560770 1268 master.cpp:1616] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0321 10:52:09.560811 1256 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0321 10:52:09.560845 1270 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0321 10:52:09.561681 1268 replica.cpp:537] Replica received write
> request for position 2 from (15614)@172.17.0.1:60994
> I0321 10:52:09.562150 1268 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 424626ns
> I0321 10:52:09.562173 1268 replica.cpp:712] Persisted action at 2
> I0321 10:52:09.563274 1270 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0321 10:52:09.563773 1270 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 444555ns
> I0321 10:52:09.563850 1270 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 49506ns
> I0321 10:52:09.563871 1270 replica.cpp:712] Persisted action at 2
> I0321 10:52:09.563899 1270 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0321 10:52:09.571946 1236 scheduler.cpp:172] Version: 0.29.0
> I0321 10:52:09.572803 1270 scheduler.cpp:437] New master detected at
> master@172.17.0.1:60994
> I0321 10:52:09.574466 1261 scheduler.cpp:337] Connected with the master
> at http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.575734 1270 scheduler.cpp:230] Sending SUBSCRIBE call to
> http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.576658 1260 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0321 10:52:09.577170 1270 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.1:32956
> I0321 10:52:09.577366 1270 master.cpp:2068] Received subscription request
> for HTTP framework 'default'
> I0321 10:52:09.577431 1270 master.cpp:1845] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0321 10:52:09.577723 1263 master.cpp:2159] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0321 10:52:09.578204 1264 hierarchical.cpp:265] Added framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000
> I0321 10:52:09.578272 1263 master.hpp:1715] Sending heartbeat to
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000
> I0321 10:52:09.578333 1264 hierarchical.cpp:1453] No resources available
> to allocate!
> I0321 10:52:09.578375 1264 hierarchical.cpp:1548] No inverse offers to
> send out!
> I0321 10:52:09.578423 1264 hierarchical.cpp:1130] Performed allocation
> for 0 slaves in 189377ns
> I0321 10:52:09.579202 1258 scheduler.cpp:612] Enqueuing event SUBSCRIBED
> received from http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.579733 1258 scheduler.cpp:612] Enqueuing event HEARTBEAT
> received from http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.580601 1258 scheduler.cpp:230] Sending REQUEST call to
> http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.581527 1255 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0321 10:52:09.581980 1270 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.1:32957
> I0321 10:52:09.582120 1270 master.cpp:2765] Processing REQUEST call for
> framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
> I0321 10:52:09.582267 1267 hierarchical.cpp:589] Received resource
> request from framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000
> I0321 10:52:09.584018 1255 master.cpp:1251] Framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default) disconnected
> I0321 10:52:09.584060 1255 master.cpp:2622] Disconnecting framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
> I0321 10:52:09.584084 1255 master.cpp:2646] Deactivating framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
> I0321 10:52:09.584233 1255 master.cpp:1275] Giving framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default) 0ns to failover
> I0321 10:52:09.584280 1266 hierarchical.cpp:375] Deactivated framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000
> I0321 10:52:09.585660 1262 master.cpp:5340] Framework failover timeout,
> removing framework 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
> I0321 10:52:09.585691 1262 master.cpp:6073] Removing framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000 (default)
> I0321 10:52:09.586012 1266 hierarchical.cpp:326] Removed framework
> 38d659fb-738e-42e0-8c40-874ef3d69231-0000
> I0321 10:52:09.594507 1269 master.cpp:1065] Master terminating
> [ OK ] ContentType/SchedulerTest.Request/0 (72 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0321 10:52:09.602182 1236 cluster.cpp:139] Creating default 'local'
> authorizer
> I0321 10:52:09.605136 1236 leveldb.cpp:174] Opened db in 2.70234ms
> I0321 10:52:09.606045 1236 leveldb.cpp:181] Compacted db in 854648ns
> I0321 10:52:09.606101 1236 leveldb.cpp:196] Created db iterator in 22950ns
> I0321 10:52:09.606117 1236 leveldb.cpp:202] Seeked to beginning of db in
> 1987ns
> I0321 10:52:09.606127 1236 leveldb.cpp:271] Iterated through 0 keys in
> the db in 287ns
> I0321 10:52:09.606189 1236 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0321 10:52:09.606730 1258 recover.cpp:447] Starting replica recovery
> I0321 10:52:09.607046 1258 recover.cpp:473] Replica is in EMPTY status
> I0321 10:52:09.608391 1267 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (15622)@172.17.0.1:60994
> I0321 10:52:09.608903 1256 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0321 10:52:09.609521 1269 recover.cpp:564] Updating replica status to
> STARTING
> I0321 10:52:09.609977 1268 master.cpp:376] Master
> a294aafa-2994-4380-a055-1657ad4aeb1a (fd5495f8511c) started on
> 172.17.0.1:60994
> I0321 10:52:09.610002 1268 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/uKqaD0/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/uKqaD0/master" --zk_session_timeout="10secs"
> I0321 10:52:09.610345 1259 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 628418ns
> I0321 10:52:09.610368 1268 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0321 10:52:09.610373 1259 replica.cpp:320] Persisted replica status to
> STARTING
> I0321 10:52:09.610384 1268 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0321 10:52:09.610414 1268 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/uKqaD0/credentials'
> I0321 10:52:09.610621 1264 recover.cpp:473] Replica is in STARTING status
> I0321 10:52:09.610782 1268 master.cpp:468] Using default 'crammd5'
> authenticator
> I0321 10:52:09.610965 1268 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0321 10:52:09.611137 1268 master.cpp:571] Authorization enabled
> I0321 10:52:09.611321 1260 whitelist_watcher.cpp:77] No whitelist given
> I0321 10:52:09.611343 1257 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0321 10:52:09.611670 1265 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (15624)@172.17.0.1:60994
> I0321 10:52:09.612175 1270 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0321 10:52:09.612669 1263 recover.cpp:564] Updating replica status to
> VOTING
> I0321 10:52:09.613292 1260 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 475384ns
> I0321 10:52:09.613322 1260 replica.cpp:320] Persisted replica status to
> VOTING
> I0321 10:52:09.613492 1260 recover.cpp:578] Successfully joined the Paxos
> group
> I0321 10:52:09.613706 1260 recover.cpp:462] Recover process terminated
> I0321 10:52:09.614096 1269 master.cpp:1806] The newly elected leader is
> master@172.17.0.1:60994 with id a294aafa-2994-4380-a055-1657ad4aeb1a
> I0321 10:52:09.614125 1269 master.cpp:1819] Elected as the leading master!
> I0321 10:52:09.614140 1269 master.cpp:1508] Recovering from registrar
> I0321 10:52:09.614280 1258 registrar.cpp:307] Recovering registrar
> I0321 10:52:09.614900 1261 log.cpp:659] Attempting to start the writer
> I0321 10:52:09.616181 1261 replica.cpp:493] Replica received implicit
> promise request from (15625)@172.17.0.1:60994 with proposal 1
> I0321 10:52:09.616613 1261 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 387232ns
> I0321 10:52:09.616638 1261 replica.cpp:342] Persisted promised to 1
> I0321 10:52:09.617393 1257 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0321 10:52:09.618605 1255 replica.cpp:388] Replica received explicit
> promise request from (15626)@172.17.0.1:60994 for position 0 with
> proposal 2
> I0321 10:52:09.619025 1255 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 373888ns
> I0321 10:52:09.619050 1255 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.620128 1262 replica.cpp:537] Replica received write
> request for position 0 from (15627)@172.17.0.1:60994
> I0321 10:52:09.620220 1262 leveldb.cpp:436] Reading position from leveldb
> took 42983ns
> I0321 10:52:09.620625 1262 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 345487ns
> I0321 10:52:09.620648 1262 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.621266 1262 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0321 10:52:09.621619 1262 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 319952ns
> I0321 10:52:09.621649 1262 replica.cpp:712] Persisted action at 0
> I0321 10:52:09.621676 1262 replica.cpp:697] Replica learned NOP action at
> position 0
> I0321 10:52:09.622372 1269 log.cpp:675] Writer started with ending
> position 0
> I0321 10:52:09.623651 1260 leveldb.cpp:436] Reading position from leveldb
> took 56734ns
> I0321 10:52:09.624791 1269 registrar.cpp:340] Successfully fetched the
> registry (0B) in 10.445824ms
> I0321 10:52:09.624966 1269 registrar.cpp:439] Applied 1 operations in
> 37159ns; attempting to update the 'registry'
> I0321 10:52:09.625689 1264 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0321 10:52:09.625845 1256 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0321 10:52:09.626632 1264 replica.cpp:537] Replica received write
> request for position 1 from (15628)@172.17.0.1:60994
> I0321 10:52:09.627161 1264 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 482207ns
> I0321 10:52:09.627188 1264 replica.cpp:712] Persisted action at 1
> I0321 10:52:09.627841 1261 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0321 10:52:09.628232 1261 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 345343ns
> I0321 10:52:09.628258 1261 replica.cpp:712] Persisted action at 1
> I0321 10:52:09.628286 1261 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0321 10:52:09.629423 1257 registrar.cpp:484] Successfully updated the
> 'registry' in 4.388096ms
> I0321 10:52:09.629590 1257 registrar.cpp:370] Successfully recovered
> registrar
> I0321 10:52:09.629726 1255 log.cpp:702] Attempting to truncate the log to
> 1
> I0321 10:52:09.629899 1259 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0321 10:52:09.630347 1255 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0321 10:52:09.630353 1269 master.cpp:1616] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0321 10:52:09.631206 1270 replica.cpp:537] Replica received write
> request for position 2 from (15629)@172.17.0.1:60994
> I0321 10:52:09.631649 1270 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 398171ns
> I0321 10:52:09.631674 1270 replica.cpp:712] Persisted action at 2
> I0321 10:52:09.632382 1259 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0321 10:52:09.632740 1259 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 325356ns
> I0321 10:52:09.632799 1259 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 33421ns
> I0321 10:52:09.632853 1259 replica.cpp:712] Persisted action at 2
> I0321 10:52:09.632886 1259 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0321 10:52:09.641461 1236 scheduler.cpp:172] Version: 0.29.0
> I0321 10:52:09.642503 1270 scheduler.cpp:437] New master detected at
> master@172.17.0.1:60994
> I0321 10:52:09.644632 1269 scheduler.cpp:337] Connected with the master
> at http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.646297 1263 scheduler.cpp:230] Sending SUBSCRIBE call to
> http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.647776 1263 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0321 10:52:09.648418 1268 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.1:32958
> I0321 10:52:09.648921 1268 master.cpp:2068] Received subscription request
> for HTTP framework 'default'
> I0321 10:52:09.649008 1268 master.cpp:1845] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0321 10:52:09.649320 1268 master.cpp:2159] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0321 10:52:09.649912 1263 hierarchical.cpp:265] Added framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000
> I0321 10:52:09.650022 1263 hierarchical.cpp:1453] No resources available
> to allocate!
> I0321 10:52:09.650068 1263 hierarchical.cpp:1548] No inverse offers to
> send out!
> I0321 10:52:09.650096 1263 hierarchical.cpp:1130] Performed allocation
> for 0 slaves in 154326ns
> I0321 10:52:09.650126 1263 master.hpp:1715] Sending heartbeat to
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000
> I0321 10:52:09.651443 1261 scheduler.cpp:612] Enqueuing event SUBSCRIBED
> received from http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.652222 1261 scheduler.cpp:612] Enqueuing event HEARTBEAT
> received from http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.653123 1267 scheduler.cpp:230] Sending REQUEST call to
> http://172.17.0.1:60994/master/api/v1/scheduler
> I0321 10:52:09.654463 1267 process.cpp:3136] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0321 10:52:09.654955 1268 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.1:32959
> I0321 10:52:09.655293 1268 master.cpp:2765] Processing REQUEST call for
> framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
> I0321 10:52:09.655441 1257 hierarchical.cpp:589] Received resource
> request from framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000
> I0321 10:52:09.659122 1265 master.cpp:1251] Framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default) disconnected
> I0321 10:52:09.659273 1265 master.cpp:2622] Disconnecting framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
> I0321 10:52:09.659454 1265 master.cpp:2646] Deactivating framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
> I0321 10:52:09.659667 1265 master.cpp:1275] Giving framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default) 0ns to failover
> I0321 10:52:09.660125 1266 hierarchical.cpp:375] Deactivated framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000
> I0321 10:52:09.661260 1263 master.cpp:5340] Framework failover timeout,
> removing framework a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
> I0321 10:52:09.661294 1263 master.cpp:6073] Removing framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000 (default)
> I0321 10:52:09.661581 1263 hierarchical.cpp:326] Removed framework
> a294aafa-2994-4380-a055-1657ad4aeb1a-0000
> I0321 10:52:09.668884 1259 master.cpp:1065] Master terminating
> [ OK ] ContentType/SchedulerTest.Request/1 (75 ms)
> [----------] 26 tests from ContentType/SchedulerTest (5391 ms total)
>
> [----------] Global test environment tear-down
> E0321 10:52:09.939385 1236 environment.cpp:752] Failed to remove
> '/tmp/LogZooKeeperTest_WriteRead_B9MRuR': No such file or directory
> E0321 10:52:09.939470 1236 environment.cpp:752] Failed to remove
> '/tmp/LogZooKeeperTest_LostZooKeeper_8JY8hc': No such file or directory
> [==========] 1030 tests from 131 test cases ran. (411027 ms total)
> [ PASSED ] 1029 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
>
> 1 FAILED TEST
> YOU HAVE 6 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1458555109-3729
> Untagged: mesos-1458555109-3729:latest
> Deleted: 64cc3cc53b165b10a15a98c4d0caaccaf5422e5dfb8fe3d4f4739a611fa7b6d0
> Deleted: 49436ae04da62b452e3317a30cf23a9392b8b399cff1b6dd3860e6c3108fa2c4
> Deleted: 4ee5fd24df872d346adabc13c0aa2f03c0d65688d0e2e96f936ac91f7e846b03
> Deleted: 07193b1951fe61d233f64bd237b53e4ee6edbe42e04d0b821fc379d74c6ee331
> Deleted: bc477332c6b27c0b4a949e0c81399ca03a7836353cc44178ab4aacb6e5283ced
> Deleted: 3c8e4dde9f57a9043f0d54717b3044bd2d5760ce7b48a598f81bae31ed490855
> Deleted: 995c15c3e0a4ba7b26624eb5cefb09e6c68a244d40328b663fd91eac320b4bc4
> Deleted: 2672eb5a58665422e69c618888c3f8ebb59378b9554c4f64664aa704fbb5435f
> Deleted: 062c6fc21a6f45e5d2eb5308fc766f68f6ac8dd72ca1740102f6d013fa3603a7
> Deleted: 102494963b7120b55d1a35ea45896da6e77636c24d0f9ddac434cd3f174092b2
> Deleted: e14ae29b7b1a6ce71abc6a340c262ab767ef421f0df2e9ae155dc6d1821629e4
> Deleted: 9c1b585c7b49eb563ca749cda9db74abf27b5cdcff4da8556c58d0bcc64d960b
> Deleted: 7e89680c9f979ce2d858654e3c3f9535db3a380f6329965bc4af3ef145822530
> Deleted: 47221266a8ae34f02f47ea45a88c0bc4d755cbcef8e2e51f76fcc6408de299bd
> Deleted: 1966214245d7956fdae8de1cd2ec96889c7fbee6c5212fda33e921210c8ccc3c
> Deleted: 2ceecc0c6b86e66dd503dcdfc6561c6d7b93a352583eff884217b5c26b651b74
> Build step 'Execute shell' marked build as failure
>