See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4552/display/redirect>
------------------------------------------
[...truncated 6.09 MB...]
3: I1207 14:00:02.195854 20092 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1207 14:00:02.196135 20092 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1207 14:00:02.196239 20092 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1207 14:00:02.196478 20092 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1207 14:00:02.196601 20092 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1207 14:00:02.198137 20092 slave.cpp:590] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1207 14:00:02.198357 20092 slave.cpp:598] Agent attributes: [ ]
3: I1207 14:00:02.198366 20092 slave.cpp:607] Agent hostname: a4f938546067
3: I1207 14:00:02.198503 20097 task_status_update_manager.cpp:181] Pausing
sending task status updates
3: I1207 14:00:02.200029 20091 state.cpp:66] Recovering state from
'/tmp/PartitionTest_RegistryGcByCount_EyRxQn/meta'
3: I1207 14:00:02.200323 20106 task_status_update_manager.cpp:207] Recovering
task status update manager
3: I1207 14:00:02.200539 20104 containerizer.cpp:674] Recovering containerizer
3: I1207 14:00:02.202057 20108 provisioner.cpp:495] Provisioner recovery
complete
3: I1207 14:00:02.202395 20097 slave.cpp:6631] Finished recovery
3: I1207 14:00:02.203146 20092 task_status_update_manager.cpp:181] Pausing
sending task status updates
3: I1207 14:00:02.203163 20095 slave.cpp:1028] New master detected at
[email protected]:46671
3: I1207 14:00:02.203241 20095 slave.cpp:1083] Detecting new master
3: I1207 14:00:02.203384 20095 slave.cpp:1110] Authenticating with master
[email protected]:46671
3: I1207 14:00:02.203444 20095 slave.cpp:1119] Using default CRAM-MD5
authenticatee
3: I1207 14:00:02.203672 20100 authenticatee.cpp:121] Creating new client SASL
connection
3: I1207 14:00:02.203922 20094 master.cpp:8738] Authenticating
slave(120)@172.17.0.2:46671
3: I1207 14:00:02.204105 20086 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(266)@172.17.0.2:46671
3: I1207 14:00:02.204347 20106 authenticator.cpp:98] Creating new server SASL
connection
3: I1207 14:00:02.204634 20101 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1207 14:00:02.204654 20101 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1207 14:00:02.204769 20089 authenticator.cpp:204] Received SASL
authentication start
3: I1207 14:00:02.204828 20089 authenticator.cpp:326] Authentication requires
more steps
3: I1207 14:00:02.204947 20102 authenticatee.cpp:259] Received SASL
authentication step
3: I1207 14:00:02.205111 20105 authenticator.cpp:232] Received SASL
authentication step
3: I1207 14:00:02.205140 20105 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'a4f938546067' server FQDN: 'a4f938546067'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1207 14:00:02.205149 20105 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1207 14:00:02.205183 20105 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1207 14:00:02.205196 20105 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'a4f938546067' server FQDN: 'a4f938546067'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1207 14:00:02.205204 20105 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1207 14:00:02.205209 20105 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1207 14:00:02.205229 20105 authenticator.cpp:318] Authentication success
3: I1207 14:00:02.205314 20085 authenticatee.cpp:299] Authentication success
3: I1207 14:00:02.205374 20099 master.cpp:8768] Successfully authenticated
principal 'test-principal' at slave(120)@172.17.0.2:46671
3: I1207 14:00:02.205447 20085 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(266)@172.17.0.2:46671
3: I1207 14:00:02.205569 20103 slave.cpp:1202] Successfully authenticated with
master [email protected]:46671
3: I1207 14:00:02.205746 20103 slave.cpp:1745] Will retry registration in
2.27682ms if necessary
3: I1207 14:00:02.205947 20108 master.cpp:6033] Received register agent message
from slave(120)@172.17.0.2:46671 (a4f938546067)
3: I1207 14:00:02.206079 20108 master.cpp:3869] Authorizing agent with
principal 'test-principal'
3: I1207 14:00:02.206550 20097 master.cpp:6109] Authorized registration of
agent at slave(120)@172.17.0.2:46671 (a4f938546067)
3: I1207 14:00:02.206641 20097 master.cpp:6202] Registering agent at
slave(120)@172.17.0.2:46671 (a4f938546067) with id
f123ac92-c342-45d9-9b39-095bbdde3106-S1
3: I1207 14:00:02.207064 20093 registrar.cpp:495] Applied 1 operations in
83018ns; attempting to update the registry
3: I1207 14:00:02.207623 20093 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.207828 20100 master.cpp:6251] Admitted agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067)
3: I1207 14:00:02.208457 20100 master.cpp:6287] Registered agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1207 14:00:02.208562 20086 slave.cpp:1248] Registered with master
[email protected]:46671; given agent ID f123ac92-c342-45d9-9b39-095bbdde3106-S1
3: I1207 14:00:02.208674 20089 task_status_update_manager.cpp:188] Resuming
sending task status updates
3: I1207 14:00:02.208672 20106 hierarchical.cpp:577] Added agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 (a4f938546067) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1207 14:00:02.208863 20086 slave.cpp:1268] Checkpointing SlaveInfo to
'/tmp/PartitionTest_RegistryGcByCount_EyRxQn/meta/slaves/f123ac92-c342-45d9-9b39-095bbdde3106-S1/slave.info'
3: I1207 14:00:02.209290 20086 slave.cpp:1347] Forwarding total oversubscribed
resources {}
3: I1207 14:00:02.209497 20104 master.cpp:7181] Received update of agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067) with total oversubscribed resources {}
3: I1207 14:00:02.209741 20104 master.cpp:7224] Ignoring update on agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067) as it reports no changes
3: I1207 14:00:02.210177 20106 hierarchical.cpp:1527] Performed allocation for
1 agents in 1.33512ms
3: I1207 14:00:02.210805 20105 master.cpp:8568] Sending 1 offers to framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.211272 20099 sched.cpp:921] Scheduler::resourceOffers took
17934ns
3: I1207 14:00:02.212074 20090 hierarchical.cpp:1527] Performed allocation for
1 agents in 222333ns
3: I1207 14:00:02.212862 20097 hierarchical.cpp:1527] Performed allocation for
1 agents in 128785ns
3: I1207 14:00:02.213497 20094 hierarchical.cpp:1527] Performed allocation for
1 agents in 114220ns
3: I1207 14:00:02.214318 20086 hierarchical.cpp:1527] Performed allocation for
1 agents in 122675ns
3: I1207 14:00:02.214419 20106 slave.cpp:6179] Current disk usage 64.42%. Max
allowed age: 1.790451944978669days
3: I1207 14:00:02.215065 20098 master.cpp:8007] Marking agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067) unreachable: health check timed out
3: I1207 14:00:02.215131 20103 hierarchical.cpp:1527] Performed allocation for
1 agents in 201328ns
3: I1207 14:00:02.215484 20095 registrar.cpp:495] Applied 1 operations in
81245ns; attempting to update the registry
3: I1207 14:00:02.215978 20095 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.216203 20089 master.cpp:8055] Marked agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 at slave(120)@172.17.0.2:46671
(a4f938546067) unreachable: health check timed out
3: I1207 14:00:02.216845 20089 master.cpp:10488] Removing offer
f123ac92-c342-45d9-9b39-095bbdde3106-O1
3: I1207 14:00:02.216878 20094 hierarchical.cpp:610] Removed agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1
3: I1207 14:00:02.216953 20102 sched.cpp:947] Rescinded offer
f123ac92-c342-45d9-9b39-095bbdde3106-O1
3: I1207 14:00:02.217005 20102 sched.cpp:958] Scheduler::offerRescinded took
15816ns
3: I1207 14:00:02.217133 20089 master.cpp:2129] Notifying framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671 of lost agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1 (a4f938546067)
3: I1207 14:00:02.217319 20105 sched.cpp:1091] Lost agent
f123ac92-c342-45d9-9b39-095bbdde3106-S1
3: I1207 14:00:02.217383 20105 sched.cpp:1102] Scheduler::slaveLost took 39135ns
3: I1207 14:00:02.218013 20084 slave.cpp:904] Agent terminating
3: I1207 14:00:02.224469 20086 master.cpp:8205] Performing explicit task state
reconciliation for 1 tasks of framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.224577 20086 master.cpp:8353] Sending explicit reconciliation
state TASK_UNREACHABLE for task 66b7f718-8347-4db9-9a58-f1662187c93f of
framework f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.224952 20106 sched.cpp:1029] Scheduler::statusUpdate took
60057ns
3: I1207 14:00:02.225414 20096 hierarchical.cpp:1527] Performed allocation for
0 agents in 53623ns
3: I1207 14:00:02.225512 20091 master.cpp:1885] Attempting to remove 1
unreachable and 0 gone agents from the registry
3: I1207 14:00:02.226027 20085 registrar.cpp:495] Applied 1 operations in
47326ns; attempting to update the registry
3: I1207 14:00:02.226537 20085 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.226954 20093 master.cpp:1945] Garbage collected 1 unreachable
and 0 gone agents from the registry
3: I1207 14:00:02.227488 20101 master.cpp:8205] Performing explicit task state
reconciliation for 1 tasks of framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.227629 20101 master.cpp:8353] Sending explicit reconciliation
state TASK_UNKNOWN for task f6def5fa-e364-4fe6-8a18-9b2b66379264 of framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.228021 20087 sched.cpp:1029] Scheduler::statusUpdate took
65236ns
3: I1207 14:00:02.228561 20089 master.cpp:8205] Performing explicit task state
reconciliation for 1 tasks of framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.228715 20089 master.cpp:8353] Sending explicit reconciliation
state TASK_UNREACHABLE for task bee35ba4-5bfe-437d-91f1-b093ead8e0ae of
framework f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.229167 20104 sched.cpp:1029] Scheduler::statusUpdate took
54116ns
3: I1207 14:00:02.231499 20099 process.cpp:3503] Handling HTTP event for
process 'metrics' with path: '/metrics/snapshot'
3: I1207 14:00:02.251171 20084 sched.cpp:2009] Asked to stop the driver
3: I1207 14:00:02.251359 20093 sched.cpp:1191] Stopping framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000
3: I1207 14:00:02.251677 20098 master.cpp:9248] Processing TEARDOWN call for
framework f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.251710 20098 master.cpp:9260] Removing framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.251730 20098 master.cpp:3330] Deactivating framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000 (default) at
[email protected]:46671
3: I1207 14:00:02.251884 20095 hierarchical.cpp:410] Deactivated framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000
3: I1207 14:00:02.252357 20094 hierarchical.cpp:349] Removed framework
f123ac92-c342-45d9-9b39-095bbdde3106-0000
3: I1207 14:00:02.253917 20092 master.cpp:1150] Master terminating
3: [ OK ] PartitionTest.RegistryGcByCount (139 ms)
3: [ RUN ] PartitionTest.RegistryGcByCountManySlaves
3: I1207 14:00:02.262639 20084 cluster.cpp:172] Creating default 'local'
authorizer
3: I1207 14:00:02.269340 20084 leveldb.cpp:174] Opened db in 5.869877ms
3: I1207 14:00:02.271600 20084 leveldb.cpp:181] Compacted db in 2.212146ms
3: I1207 14:00:02.271663 20084 leveldb.cpp:196] Created db iterator in 25971ns
3: I1207 14:00:02.271674 20084 leveldb.cpp:202] Seeked to beginning of db in
1475ns
3: I1207 14:00:02.271682 20084 leveldb.cpp:271] Iterated through 0 keys in the
db in 688ns
3: I1207 14:00:02.271823 20084 replica.cpp:779] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
3: I1207 14:00:02.276427 20087 recover.cpp:451] Starting replica recovery
3: I1207 14:00:02.277479 20087 recover.cpp:477] Replica is in EMPTY status
3: I1207 14:00:02.280297 20096 replica.cpp:676] Replica in EMPTY status
received a broadcasted recover request from __req_res__(1)@172.17.0.2:46671
3: I1207 14:00:02.281594 20085 master.cpp:454] Master
2f5a2ced-e95a-40eb-a32e-8eae48dd93c8 (a4f938546067) started on 172.17.0.2:46671
3: I1207 14:00:02.281639 20085 master.cpp:456] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true"
--authenticate_http_frameworks="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/OxJ4P3/credentials"
--filter_gpu_resources="true" --framework_sorter="drf" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_framework_authenticators="basic" --initialize_driver_logging="true"
--log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
--max_agent_ping_timeouts="5" --max_completed_frameworks="50"
--max_completed_tasks_per_framework="1000"
--max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false"
--recovery_agent_removal_limit="100%" --registry="replicated_log"
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
--registry_max_agent_age="2weeks" --registry_max_agent_count="1"
--registry_store_timeout="100secs" --registry_strict="false"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/OxJ4P3/master"
--zk_session_timeout="10secs"
3: I1207 14:00:02.282119 20085 master.cpp:505] Master only allowing
authenticated frameworks to register
3: I1207 14:00:02.282127 20085 master.cpp:511] Master only allowing
authenticated agents to register
3: I1207 14:00:02.282131 20085 master.cpp:517] Master only allowing
authenticated HTTP frameworks to register
3: I1207 14:00:02.282136 20085 credentials.hpp:37] Loading credentials for
authentication from '/tmp/OxJ4P3/credentials'
3: I1207 14:00:02.282560 20085 master.cpp:561] Using default 'crammd5'
authenticator
3: I1207 14:00:02.282763 20103 recover.cpp:197] Received a recover response
from a replica in EMPTY status
3: I1207 14:00:02.282801 20085 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1207 14:00:02.283030 20085 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1207 14:00:02.283229 20085 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1207 14:00:02.283419 20085 master.cpp:640] Authorization enabled
3: I1207 14:00:02.283646 20087 hierarchical.cpp:175] Initialized hierarchical
allocator process
3: I1207 14:00:02.283680 20098 whitelist_watcher.cpp:77] No whitelist given
3: I1207 14:00:02.284252 20105 recover.cpp:568] Updating replica status to
STARTING
3: I1207 14:00:02.285770 20092 leveldb.cpp:304] Persisting metadata (8 bytes)
to leveldb took 1.215549ms
3: I1207 14:00:02.285806 20092 replica.cpp:322] Persisted replica status to
STARTING
3: I1207 14:00:02.286370 20088 recover.cpp:477] Replica is in STARTING status
3: I1207 14:00:02.287664 20093 replica.cpp:676] Replica in STARTING status
received a broadcasted recover request from __req_res__(2)@172.17.0.2:46671
3: I1207 14:00:02.287976 20101 recover.cpp:197] Received a recover response
from a replica in STARTING status
3: I1207 14:00:02.288548 20107 recover.cpp:568] Updating replica status to
VOTING
3: I1207 14:00:02.288609 20087 master.cpp:2212] Elected as the leading master!
3: I1207 14:00:02.288638 20087 master.cpp:1692] Recovering from registrar
3: I1207 14:00:02.288956 20091 registrar.cpp:347] Recovering registrar
3: I1207 14:00:02.289616 20103 leveldb.cpp:304] Persisting metadata (8 bytes)
to leveldb took 950304ns
3: I1207 14:00:02.289639 20103 replica.cpp:322] Persisted replica status to
VOTING
3: I1207 14:00:02.289870 20095 recover.cpp:582] Successfully joined the Paxos
group
3: I1207 14:00:02.290257 20095 recover.cpp:466] Recover process terminated
3: I1207 14:00:02.291518 20108 log.cpp:536] Attempting to start the writer
3: I1207 14:00:02.295531 20099 replica.cpp:496] Replica received implicit
promise request from __req_res__(3)@172.17.0.2:46671 with proposal 1
3: I1207 14:00:02.296864 20099 leveldb.cpp:304] Persisting metadata (8 bytes)
to leveldb took 1.271675ms
3: I1207 14:00:02.296919 20099 replica.cpp:344] Persisted promised to 1
3: I1207 14:00:02.298609 20087 coordinator.cpp:238] Coordinator attempting to
fill missing positions
3: I1207 14:00:02.300621 20102 replica.cpp:391] Replica received explicit
promise request from __req_res__(4)@172.17.0.2:46671 for position 0 with
proposal 2
3: I1207 14:00:02.301746 20102 leveldb.cpp:341] Persisting action (8 bytes) to
leveldb took 1.05374ms
3: I1207 14:00:02.301818 20102 replica.cpp:711] Persisted action NOP at
position 0
3: I1207 14:00:02.303675 20092 replica.cpp:540] Replica received write request
for position 0 from __req_res__(5)@172.17.0.2:46671
3: I1207 14:00:02.303741 20092 leveldb.cpp:436] Reading position from leveldb
took 40609ns
3: I1207 14:00:02.304811 20092 leveldb.cpp:341] Persisting action (14 bytes) to
leveldb took 989762ns
3: I1207 14:00:02.304831 20092 replica.cpp:711] Persisted action NOP at
position 0
3: I1207 14:00:02.306301 20089 replica.cpp:694] Replica received learned notice
for position 0 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.307377 20089 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 1.027353ms
3: I1207 14:00:02.307428 20089 replica.cpp:711] Persisted action NOP at
position 0
3: I1207 14:00:02.308440 20099 log.cpp:552] Writer started with ending position 0
3: I1207 14:00:02.313079 20096 leveldb.cpp:436] Reading position from leveldb
took 47976ns
3: I1207 14:00:02.315613 20092 registrar.cpp:391] Successfully fetched the
registry (0B) in 26.599936ms
3: I1207 14:00:02.315747 20092 registrar.cpp:495] Applied 1 operations in
47286ns; attempting to update the registry
3: I1207 14:00:02.317581 20108 log.cpp:560] Attempting to append 174 bytes to
the log
3: I1207 14:00:02.318027 20088 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 1
3: I1207 14:00:02.319218 20097 replica.cpp:540] Replica received write request
for position 1 from __req_res__(6)@172.17.0.2:46671
3: I1207 14:00:02.320533 20097 leveldb.cpp:341] Persisting action (193 bytes)
to leveldb took 1.222479ms
3: I1207 14:00:02.320570 20097 replica.cpp:711] Persisted action APPEND at
position 1
3: I1207 14:00:02.321853 20105 replica.cpp:694] Replica received learned notice
for position 1 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.322968 20105 leveldb.cpp:341] Persisting action (195 bytes)
to leveldb took 1.077884ms
3: I1207 14:00:02.323016 20105 replica.cpp:711] Persisted action APPEND at
position 1
3: I1207 14:00:02.324987 20086 registrar.cpp:552] Successfully updated the
registry in 9.084928ms
3: I1207 14:00:02.325326 20086 registrar.cpp:424] Successfully recovered
registrar
3: I1207 14:00:02.325541 20095 log.cpp:579] Attempting to truncate the log to 1
3: I1207 14:00:02.325964 20091 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 2
3: I1207 14:00:02.326059 20092 master.cpp:1805] Recovered 0 agents from the
registry (135B); allowing 10mins for agents to re-register
3: I1207 14:00:02.326086 20089 hierarchical.cpp:213] Skipping recovery of
hierarchical allocator: nothing to recover
3: I1207 14:00:02.327353 20097 replica.cpp:540] Replica received write request
for position 2 from __req_res__(7)@172.17.0.2:46671
3: I1207 14:00:02.328472 20097 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 1.068055ms
3: I1207 14:00:02.328531 20097 replica.cpp:711] Persisted action TRUNCATE at
position 2
3: I1207 14:00:02.329229 20096 replica.cpp:694] Replica received learned notice
for position 2 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.330322 20096 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 1.054974ms
3: I1207 14:00:02.330426 20096 leveldb.cpp:399] Deleting ~1 keys from leveldb
took 56670ns
3: I1207 14:00:02.330440 20096 replica.cpp:711] Persisted action TRUNCATE at
position 2
3: I1207 14:00:02.332427 20095 registrar.cpp:495] Applied 1 operations in
109964ns; attempting to update the registry
3: I1207 14:00:02.333271 20090 log.cpp:560] Attempting to append 231 bytes to
the log
3: I1207 14:00:02.333402 20089 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 3
3: I1207 14:00:02.334357 20104 replica.cpp:540] Replica received write request
for position 3 from __req_res__(8)@172.17.0.2:46671
3: I1207 14:00:02.335909 20104 leveldb.cpp:341] Persisting action (250 bytes)
to leveldb took 1.493989ms
3: I1207 14:00:02.335957 20104 replica.cpp:711] Persisted action APPEND at
position 3
3: I1207 14:00:02.336695 20104 replica.cpp:694] Replica received learned notice
for position 3 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.337673 20104 leveldb.cpp:341] Persisting action (252 bytes)
to leveldb took 952256ns
3: I1207 14:00:02.337697 20104 replica.cpp:711] Persisted action APPEND at
position 3
3: I1207 14:00:02.338766 20108 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.338883 20095 log.cpp:579] Attempting to truncate the log to 3
3: I1207 14:00:02.339040 20086 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 4
3: I1207 14:00:02.339805 20098 replica.cpp:540] Replica received write request
for position 4 from __req_res__(9)@172.17.0.2:46671
3: I1207 14:00:02.340708 20098 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 868334ns
3: I1207 14:00:02.340730 20098 replica.cpp:711] Persisted action TRUNCATE at
position 4
3: I1207 14:00:02.341681 20106 replica.cpp:694] Replica received learned notice
for position 4 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.342751 20106 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 1.027489ms
3: I1207 14:00:02.342842 20106 leveldb.cpp:399] Deleting ~2 keys from leveldb
took 52065ns
3: I1207 14:00:02.342867 20106 replica.cpp:711] Persisted action TRUNCATE at
position 4
3: I1207 14:00:02.344377 20107 registrar.cpp:495] Applied 1 operations in
118710ns; attempting to update the registry
3: I1207 14:00:02.345196 20096 log.cpp:560] Attempting to append 232 bytes to
the log
3: I1207 14:00:02.345413 20108 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 5
3: I1207 14:00:02.346276 20091 replica.cpp:540] Replica received write request
for position 5 from __req_res__(10)@172.17.0.2:46671
3: I1207 14:00:02.347331 20091 leveldb.cpp:341] Persisting action (251 bytes)
to leveldb took 995845ns
3: I1207 14:00:02.347386 20091 replica.cpp:711] Persisted action APPEND at
position 5
3: I1207 14:00:02.348235 20093 replica.cpp:694] Replica received learned notice
for position 5 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.349256 20093 leveldb.cpp:341] Persisting action (253 bytes)
to leveldb took 976886ns
3: I1207 14:00:02.349293 20093 replica.cpp:711] Persisted action APPEND at
position 5
3: I1207 14:00:02.350626 20104 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.350776 20107 log.cpp:579] Attempting to truncate the log to 5
3: I1207 14:00:02.350953 20088 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 6
3: I1207 14:00:02.351893 20108 replica.cpp:540] Replica received write request
for position 6 from __req_res__(11)@172.17.0.2:46671
3: I1207 14:00:02.352978 20108 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 1.024686ms
3: I1207 14:00:02.353020 20108 replica.cpp:711] Persisted action TRUNCATE at
position 6
3: I1207 14:00:02.353900 20097 replica.cpp:694] Replica received learned notice
for position 6 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.354944 20097 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 999531ns
3: I1207 14:00:02.354995 20097 leveldb.cpp:399] Deleting ~2 keys from leveldb
took 32230ns
3: I1207 14:00:02.355010 20097 replica.cpp:711] Persisted action TRUNCATE at
position 6
3: I1207 14:00:02.356228 20101 registrar.cpp:495] Applied 1 operations in
87913ns; attempting to update the registry
3: I1207 14:00:02.357167 20104 log.cpp:560] Attempting to append 287 bytes to
the log
3: I1207 14:00:02.357328 20106 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 7
3: I1207 14:00:02.358137 20095 replica.cpp:540] Replica received write request
for position 7 from __req_res__(12)@172.17.0.2:46671
3: I1207 14:00:02.359094 20095 leveldb.cpp:341] Persisting action (306 bytes)
to leveldb took 908199ns
3: I1207 14:00:02.359131 20095 replica.cpp:711] Persisted action APPEND at
position 7
3: I1207 14:00:02.360000 20098 replica.cpp:694] Replica received learned notice
for position 7 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.361078 20098 leveldb.cpp:341] Persisting action (308 bytes)
to leveldb took 1.04771ms
3: I1207 14:00:02.361104 20098 replica.cpp:711] Persisted action APPEND at
position 7
3: I1207 14:00:02.362315 20097 registrar.cpp:552] Successfully updated the
registry in 0ns
3: I1207 14:00:02.362632 20103 log.cpp:579] Attempting to truncate the log to 7
3: I1207 14:00:02.362818 20101 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 8
3: I1207 14:00:02.363788 20104 replica.cpp:540] Replica received write request
for position 8 from __req_res__(13)@172.17.0.2:46671
3: I1207 14:00:02.364890 20104 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 1.057477ms
3: I1207 14:00:02.364936 20104 replica.cpp:711] Persisted action TRUNCATE at
position 8
3: I1207 14:00:02.365561 20089 replica.cpp:694] Replica received learned notice
for position 8 from log-network(1)@172.17.0.2:46671
3: I1207 14:00:02.366545 20089 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 929726ns
3: I1207 14:00:02.366660 20089 leveldb.cpp:399] Deleting ~2 keys from leveldb
took 70893ns
3: I1207 14:00:02.366703 20089 replica.cpp:711] Persisted action TRUNCATE at
position 8
3: I1207 14:00:02.367949 20102 registrar.cpp:495] Applied 1 operations in
105104ns; attempting to update the registry
3: I1207 14:00:02.369029 20097 log.cpp:560] Attempting to append 286 bytes to
the log
3: I1207 14:00:02.369206 20099 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 9
3: I1207 14:00:02.370198 20106 replica.cpp:540] Replica received write request
for position 9 from __req_res__(14)@172.17.0.2:46671
3: I1207 14:00:02.371377 20106 leveldb.cpp:341] Persisting action (305 bytes)
to leveldb took 1.132848ms
3: I1207 14:00:02.371423 20106 replica.cpp:711] Persisted action APPEND at
position 9
3: I1207 14:00:02.372103 20085 replica.cpp:694] Replica received learned notice
for position 9 from log-network(1)@172.17.0.2:46671
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1512652881-22772
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user [email protected]
Not sending mail to unregistered user [email protected]
