See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4473/display/redirect?page=changes>
Changes:
[bmahler] Added a Future constructor for Try<Future<T>>.
------------------------------------------
[...truncated 3.17 MB...]
3: I1120 01:19:54.442893 17369 slave.cpp:4998] Forwarding the update
TASK_RUNNING (UUID: de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to master@172.17.0.2:36246
3: I1120 01:19:54.443179 17369 slave.cpp:4892] Status update manager
successfully handled status update TASK_RUNNING (UUID:
de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.443251 17369 slave.cpp:4908] Sending acknowledgement for
status update TASK_RUNNING (UUID: de2978ca-2f47-4dbe-a309-96360d9fad76) for
task 0 of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to
executor(4)@172.17.0.2:36246
3: I1120 01:19:54.443423 17364 master.cpp:7247] Status update TASK_RUNNING
(UUID: de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 from agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.443470 17359 exec.cpp:388] Executor received status update
acknowledgement de2978ca-2f47-4dbe-a309-96360d9fad76 for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.443492 17364 master.cpp:7309] Forwarding status update
TASK_RUNNING (UUID: de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.443706 17364 master.cpp:9502] Updating the state of task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (latest state:
TASK_RUNNING, status update state: TASK_RUNNING)
3: I1120 01:19:54.443951 17371 sched.cpp:1029] Scheduler::statusUpdate took
59896ns
3: I1120 01:19:54.444389 17366 master.cpp:5791] Processing ACKNOWLEDGE call
de2978ca-2f47-4dbe-a309-96360d9fad76 for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (default) at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246 on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0
3: I1120 01:19:54.444552 17366 master.cpp:1151] Master terminating
3: I1120 01:19:54.444767 17357 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0
of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: W1120 01:19:54.444687 17366 master.cpp:9582] Removing task 0 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":16.0},"type":"SCALAR"}]
of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) in non-removable state TASK_RUNNING
3: I1120 01:19:54.445087 17357 slave.cpp:3801] Status update manager
successfully handled status update acknowledgement (UUID:
de2978ca-2f47-4dbe-a309-96360d9fad76) for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.445274 17355 hierarchical.cpp:633] Removed agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0
3: I1120 01:19:54.445467 17366 master.cpp:9625] Removing executor 'default'
with resources [] of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on
agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.446218 17372 hierarchical.cpp:358] Removed framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.446455 17364 slave.cpp:5130] Got exited event for
master@172.17.0.2:36246
3: W1120 01:19:54.446487 17364 slave.cpp:5135] Master disconnected! Waiting for
a new master to be elected
3: I1120 01:19:54.452167 17350 cluster.cpp:162] Creating default 'local'
authorizer
3: I1120 01:19:54.456352 17351 master.cpp:448] Master
120b9d3b-32a5-4128-bed0-4e918b650a76 (ef3e6d5c1e51) started on 172.17.0.2:36246
3: I1120 01:19:54.456378 17351 master.cpp:450] 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/VGD2kD/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="in_memory"
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--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/VGD2kD/master"
--zk_session_timeout="10secs"
3: I1120 01:19:54.456692 17351 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1120 01:19:54.456701 17351 master.cpp:505] Master only allowing
authenticated agents to register
3: I1120 01:19:54.456704 17351 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1120 01:19:54.456710 17351 credentials.hpp:37] Loading credentials for
authentication from '/tmp/VGD2kD/credentials'
3: I1120 01:19:54.456993 17351 master.cpp:555] Using default 'crammd5'
authenticator
3: I1120 01:19:54.457142 17351 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1120 01:19:54.457296 17351 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1120 01:19:54.457432 17351 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1120 01:19:54.457553 17351 master.cpp:634] Authorization enabled
3: I1120 01:19:54.457696 17363 hierarchical.cpp:173] Initialized hierarchical
allocator process
3: I1120 01:19:54.457764 17356 whitelist_watcher.cpp:77] No whitelist given
3: I1120 01:19:54.461360 17373 master.cpp:2213] Elected as the leading master!
3: I1120 01:19:54.461390 17373 master.cpp:1693] Recovering from registrar
3: I1120 01:19:54.461503 17371 registrar.cpp:347] Recovering registrar
3: I1120 01:19:54.462060 17371 registrar.cpp:391] Successfully fetched the
registry (0B) in 517888ns
3: I1120 01:19:54.462154 17371 registrar.cpp:495] Applied 1 operations in
26237ns; attempting to update the registry
3: I1120 01:19:54.462659 17371 registrar.cpp:552] Successfully updated the
registry in 456704ns
3: I1120 01:19:54.462779 17371 registrar.cpp:424] Successfully recovered
registrar
3: I1120 01:19:54.463153 17363 hierarchical.cpp:211] Skipping recovery of
hierarchical allocator: nothing to recover
3: I1120 01:19:54.463145 17365 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1120 01:19:54.463969 17356 status_update_manager.cpp:177] Pausing sending
status updates
3: I1120 01:19:54.463987 17361 slave.cpp:1007] New master detected at
master@172.17.0.2:36246
3: I1120 01:19:54.464140 17361 slave.cpp:1042] Detecting new master
3: I1120 01:19:54.466616 17359 slave.cpp:1069] Authenticating with master
master@172.17.0.2:36246
3: I1120 01:19:54.466712 17359 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1120 01:19:54.467034 17352 authenticatee.cpp:121] Creating new client SASL
connection
3: I1120 01:19:54.467422 17372 master.cpp:8285] Authenticating
slave(48)@172.17.0.2:36246
3: I1120 01:19:54.467561 17358 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(107)@172.17.0.2:36246
3: I1120 01:19:54.467814 17373 authenticator.cpp:98] Creating new server SASL
connection
3: I1120 01:19:54.468078 17366 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1120 01:19:54.468106 17366 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1120 01:19:54.468241 17364 authenticator.cpp:204] Received SASL
authentication start
3: I1120 01:19:54.468300 17364 authenticator.cpp:326] Authentication requires
more steps
3: I1120 01:19:54.468416 17368 authenticatee.cpp:259] Received SASL
authentication step
3: I1120 01:19:54.468577 17353 authenticator.cpp:232] Received SASL
authentication step
3: I1120 01:19:54.468611 17353 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'ef3e6d5c1e51' server FQDN: 'ef3e6d5c1e51'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1120 01:19:54.468622 17353 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1120 01:19:54.468658 17353 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1120 01:19:54.468684 17353 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'ef3e6d5c1e51' server FQDN: 'ef3e6d5c1e51'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1120 01:19:54.468693 17353 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1120 01:19:54.468699 17353 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1120 01:19:54.468719 17353 authenticator.cpp:318] Authentication success
3: I1120 01:19:54.468894 17362 authenticatee.cpp:299] Authentication success
3: I1120 01:19:54.468971 17360 master.cpp:8315] Successfully authenticated
principal 'test-principal' at slave(48)@172.17.0.2:36246
3: I1120 01:19:54.469023 17371 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(107)@172.17.0.2:36246
3: I1120 01:19:54.469280 17362 slave.cpp:1161] Successfully authenticated with
master master@172.17.0.2:36246
3: I1120 01:19:54.469904 17362 slave.cpp:1682] Will retry registration in
9.633259ms if necessary
3: I1120 01:19:54.470635 17365 master.cpp:6350] Received re-register agent
message from agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at
slave(48)@172.17.0.2:36246 (ef3e6d5c1e51)
3: I1120 01:19:54.470897 17365 master.cpp:3870] Authorizing agent with
principal 'test-principal'
3: I1120 01:19:54.471434 17367 master.cpp:6419] Authorized re-registration of
agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.471511 17367 master.cpp:6599] Re-registering agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: W1120 01:19:54.471983 17351 master.hpp:2270] Allowing UNKNOWN agent to
reregister: hostname: "ef3e6d5c1e51"
3: resources {
3: name: "cpus"
3: type: SCALAR
3: scalar {
3: value: 2
3: }
3: role: "*"
3: }
3: resources {
3: name: "mem"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "disk"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: role: "*"
3: }
3: resources {
3: name: "ports"
3: type: RANGES
3: ranges {
3: range {
3: begin: 31000
3: end: 32000
3: }
3: }
3: role: "*"
3: }
3: id {
3: value: "2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0"
3: }
3: checkpoint: true
3: port: 36246
3: I1120 01:19:54.472280 17351 registrar.cpp:495] Applied 1 operations in
327520ns; attempting to update the registry
3: I1120 01:19:54.473042 17369 registrar.cpp:552] Successfully updated the
registry in 691200ns
3: I1120 01:19:54.473544 17372 master.cpp:6668] Re-admitted agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.474344 17372 master.cpp:10770] Adding task 0 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":16.0},"type":"SCALAR"}]
on agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.475162 17372 master.cpp:6846] Re-registered agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) with
[{"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: I1120 01:19:54.475545 17372 master.cpp:6929] Recovering framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 from re-registering agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.475613 17373 slave.cpp:1340] Re-registered with master
master@172.17.0.2:36246
3: I1120 01:19:54.475754 17366 hierarchical.cpp:600] Added agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 (ef3e6d5c1e51) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: cpus(allocated: *):1; mem(allocated:
*):16)
3: I1120 01:19:54.475819 17373 slave.cpp:1397] Forwarding total oversubscribed
resources {}
3: I1120 01:19:54.475792 17354 status_update_manager.cpp:184] Resuming sending
status updates
3: I1120 01:19:54.476279 17366 hierarchical.cpp:1457] Performed allocation for
1 agents in 234519ns
3: I1120 01:19:54.476560 17374 slave.cpp:5130] Got exited event for
executor(4)@172.17.0.2:36246
3: I1120 01:19:54.476558 17372 master.cpp:7064] Received update of agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) with total oversubscribed resources {}
3: I1120 01:19:54.476794 17361 slave.cpp:5539] Executor 'default' of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 exited with status 0
3: I1120 01:19:54.476826 17372 master.cpp:7082] Ignoring update on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) as it reports no changes
3: I1120 01:19:54.476953 17361 slave.cpp:4517] Handling status update
TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 from @0.0.0.0:0
3: I1120 01:19:54.477411 17366 hierarchical.cpp:306] Added framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.477478 17366 hierarchical.cpp:419] Deactivated framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.477558 17369 master.cpp:7459] Executor 'default' of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51): exited with status 0
3: I1120 01:19:54.477641 17369 master.cpp:9625] Removing executor 'default'
with resources [] of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on
agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: W1120 01:19:54.477869 17369 master.cpp:7471] Not forwarding exited executor
message for executor 'default' of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) because the framework is disconnected
3: I1120 01:19:54.478091 17365 status_update_manager.cpp:323] Received status
update TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.478145 17371 sched.cpp:330] Scheduler::disconnected took
18925ns
3: I1120 01:19:54.478168 17371 sched.cpp:336] New master detected at
master@172.17.0.2:36246
3: I1120 01:19:54.478240 17365 status_update_manager.cpp:377] Forwarding update
TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to the agent
3: I1120 01:19:54.478302 17371 sched.cpp:396] Authenticating with master
master@172.17.0.2:36246
3: I1120 01:19:54.478327 17371 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1120 01:19:54.478437 17374 slave.cpp:4998] Forwarding the update
TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to master@172.17.0.2:36246
3: I1120 01:19:54.478600 17365 authenticatee.cpp:121] Creating new client SASL
connection
3: I1120 01:19:54.478679 17374 slave.cpp:4892] Status update manager
successfully handled status update TASK_FAILED (UUID:
26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.478870 17360 master.cpp:8285] Authenticating
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.479043 17372 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(108)@172.17.0.2:36246
3: I1120 01:19:54.479394 17356 authenticator.cpp:98] Creating new server SASL
connection
3: I1120 01:19:54.479706 17361 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1120 01:19:54.479735 17361 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1120 01:19:54.479852 17358 authenticator.cpp:204] Received SASL
authentication start
3: I1120 01:19:54.479903 17358 authenticator.cpp:326] Authentication requires
more steps
3: I1120 01:19:54.480022 17353 authenticatee.cpp:259] Received SASL
authentication step
3: I1120 01:19:54.480157 17364 authenticator.cpp:232] Received SASL
authentication step
3: I1120 01:19:54.480207 17364 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'ef3e6d5c1e51' server FQDN: 'ef3e6d5c1e51'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1120 01:19:54.480237 17364 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1120 01:19:54.480262 17364 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1120 01:19:54.480283 17364 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'ef3e6d5c1e51' server FQDN: 'ef3e6d5c1e51'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1120 01:19:54.480293 17364 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1120 01:19:54.480298 17364 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1120 01:19:54.480310 17364 authenticator.cpp:318] Authentication success
3: I1120 01:19:54.480446 17366 authenticatee.cpp:299] Authentication success
3: I1120 01:19:54.480537 17368 master.cpp:8315] Successfully authenticated
principal 'test-principal' at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.480597 17370 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(108)@172.17.0.2:36246
3: I1120 01:19:54.480813 17366 sched.cpp:502] Successfully authenticated with
master master@172.17.0.2:36246
3: I1120 01:19:54.480844 17366 sched.cpp:824] Sending SUBSCRIBE call to
master@172.17.0.2:36246
3: I1120 01:19:54.481005 17366 sched.cpp:857] Will retry registration in
1.262061411secs if necessary
3: I1120 01:19:54.481261 17355 master.cpp:2961] Received SUBSCRIBE call for
framework 'default' at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.481371 17355 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
3: I1120 01:19:54.481863 17367 master.cpp:3041] Subscribing framework default
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1120 01:19:54.481936 17367 master.cpp:6967] Updating info for framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.482264 17352 hierarchical.cpp:385] Activated framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.482537 17351 sched.cpp:751] Framework registered with
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.482561 17374 slave.cpp:3438] Updating info for framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 with pid updated to
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.482653 17351 sched.cpp:765] Scheduler::registered took 73136ns
3: I1120 01:19:54.482669 17365 status_update_manager.cpp:184] Resuming sending
status updates
3: W1120 01:19:54.482702 17365 status_update_manager.cpp:191] Resending status
update TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.482738 17365 status_update_manager.cpp:377] Forwarding update
TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to the agent
3: I1120 01:19:54.482960 17356 slave.cpp:4998] Forwarding the update
TASK_FAILED (UUID: 26f4f9b4-62b9-4485-8382-c7922ec98bcd) for task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 to master@172.17.0.2:36246
3: I1120 01:19:54.483047 17350 sched.cpp:2009] Asked to stop the driver
3: I1120 01:19:54.483168 17364 sched.cpp:1191] Stopping framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.483398 17359 master.cpp:8792] Processing TEARDOWN call for
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (default) at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.483431 17359 master.cpp:8804] Removing framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (default) at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.483444 17359 master.cpp:3331] Deactivating framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (default) at
scheduler-abd98832-286c-440a-ae3e-4fe9613cd230@172.17.0.2:36246
3: I1120 01:19:54.483594 17359 master.cpp:9502] Updating the state of task 0 of
framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 (latest state: TASK_KILLED,
status update state: TASK_KILLED)
3: I1120 01:19:54.483613 17368 slave.cpp:3267] Asked to shut down framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 by master@172.17.0.2:36246
3: I1120 01:19:54.483649 17368 slave.cpp:3292] Shutting down framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.483705 17368 slave.cpp:5643] Cleaning up executor 'default'
of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 at
executor(4)@172.17.0.2:36246
3: I1120 01:19:54.484001 17363 gc.cpp:90] Scheduling
'/tmp/FaultToleranceTest_ReregisterFrameworkExitedExecutor_ccqohf/slaves/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0/frameworks/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000/executors/default/runs/302bc810-15aa-4ccf-8e7d-dc7bf6d2c97b'
for gc 6.99999439975704days in the future
3: I1120 01:19:54.484069 17352 hierarchical.cpp:1457] Performed allocation for
1 agents in 1.595827ms
3: I1120 01:19:54.484114 17368 slave.cpp:5750] Cleaning up framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.484045 17359 master.cpp:9596] Removing task 0 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":16.0},"type":"SCALAR"}]
of framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.484247 17352 hierarchical.cpp:419] Deactivated framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.484295 17373 status_update_manager.cpp:285] Closing status
update streams for framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.484346 17373 status_update_manager.cpp:531] Cleaning up
status update stream for task 0 of framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.484370 17363 gc.cpp:90] Scheduling
'/tmp/FaultToleranceTest_ReregisterFrameworkExitedExecutor_ccqohf/slaves/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0/frameworks/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000/executors/default'
for gc 6.99999439768593days in the future
3: I1120 01:19:54.484606 17363 gc.cpp:90] Scheduling
'/tmp/FaultToleranceTest_ReregisterFrameworkExitedExecutor_ccqohf/slaves/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0/frameworks/2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000'
for gc 6.99999439525333days in the future
3: W1120 01:19:54.484817 17359 master.cpp:7961] Master returning resources
offered to framework 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000 because the
framework has terminated or is inactive
3: I1120 01:19:54.484928 17352 hierarchical.cpp:1132] Recovered cpus(allocated:
*):1; mem(allocated: *):16 (total: cpus:2; mem:1024; disk:1024;
ports:[31000-32000], allocated: cpus(allocated: *):1; mem(allocated: *):1008;
disk(allocated: *):1024; ports(allocated: *):[31000-32000]) on agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 from framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.485225 17369 slave.cpp:883] Agent terminating
3: I1120 01:19:54.485388 17370 master.cpp:1309] Agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51) disconnected
3: I1120 01:19:54.485410 17370 master.cpp:3368] Disconnecting agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.485453 17370 master.cpp:3387] Deactivating agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 at slave(48)@172.17.0.2:36246
(ef3e6d5c1e51)
3: I1120 01:19:54.486171 17352 hierarchical.cpp:358] Removed framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.486536 17352 hierarchical.cpp:1132] Recovered cpus(allocated:
*):1; mem(allocated: *):1008; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000],
allocated: {}) on agent 2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 from framework
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-0000
3: I1120 01:19:54.486677 17352 hierarchical.cpp:697] Agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0 deactivated
3: I1120 01:19:54.490701 17350 master.cpp:1151] Master terminating
3: I1120 01:19:54.491350 17365 hierarchical.cpp:633] Removed agent
2ac5ea8d-b229-4965-b7fd-6f024db93ea5-S0
3: [ OK ] FaultToleranceTest.ReregisterFrameworkExitedExecutor (119 ms)
3: [ RUN ] FaultToleranceTest.ForwardStatusUpdateUnknownExecutor
3: I1120 01:19:54.500409 17350 cluster.cpp:162] Creating default 'local'
authorizer
3: I1120 01:19:54.504853 17354 master.cpp:448] Master
084a8b9e-a316-4d37-9e86-c60375b3c241 (ef3e6d5c1e51) started on 172.17.0.2:36246
3: I1120 01:19:54.504884 17354 master.cpp:450] 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/51fRmH/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="in_memory"
--registry_fetch_timeout="1mins" --registry_gc_interval="15mins"
--registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--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/51fRmH/master"
--zk_session_timeout="10secs"
3: I1120 01:19:54.505308 17354 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1120 01:19:54.505321 17354 master.cpp:505] Master only allowing
authenticated agents to register
3: I1120 01:19:54.505327 17354 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1120 01:19:54.505336 17354 credentials.hpp:37] Loading credentials for
authentication from '/tmp/51fRmH/credentials'
3: I1120 01:19:54.505754 17354 master.cpp:555] Using default 'crammd5'
authenticator
3: I1120 01:19:54.505969 17354 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1120 01:19:54.506198 17354 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1120 01:19:54.506405 17354 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1120 01:19:54.506594 17354 master.cpp:634] Authorization enabled
3: I1120 01:19:54.506777 17373 hierarchical.cpp:173] Initialized hierarchical
allocator process
3: I1120 01:19:54.506814 17352 whitelist_watcher.cpp:77] No whitelist given
3: I1120 01:19:54.510653 17372 master.cpp:2213] Elected as the leading master!
3: I1120 01:19:54.510706 17372 master.cpp:1693] Recovering from registrar
3: I1120 01:19:54.510902 17359 registrar.cpp:347] Recovering registrar
3: I1120 01:19:54.511620 17359 registrar.cpp:391] Successfully fetched the
registry (0B) in 662016ns
3: I1120 01:19:54.511746 17359 registrar.cpp:495] Applied 1 operations in
33034ns; attempting to update the registry
3: I1120 01:19:54.512501 17359 registrar.cpp:552] Successfully updated the
registry in 690176ns
3: I1120 01:19:54.512653 17359 registrar.cpp:424] Successfully recovered
registrar
3: I1120 01:19:54.513139 17371 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1120 01:19:54.513212 17366 hierarchical.cpp:211] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1120 01:19:54.520340 17350 process.cpp:3185] Attempted to spawn already
running process files@172.17.0.2:36246
3: I1120 01:19:54.520833 17350 cluster.cpp:448] Creating default 'local'
authorizer
3: I1120 01:19:54.524412 17356 slave.cpp:262] Mesos agent started on
(49)@172.17.0.2:36246
3: I1120 01:19:54.524441 17356 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true"
--authenticatee="crammd5" --authentication_backoff_factor="1secs"
--authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup"
--cgroups_limit_swap="false" --cgroups_root="mesos"
--container_disk_watch_interval="15secs" --containerizers="mesos"
--credential="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false"
--disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs"
--docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true"
--systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_a35sAj"
--zk_session_timeout="10secs"
3: I1120 01:19:54.525048 17356 credentials.hpp:86] Loading credential for
authentication from
'/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/credential'
3: I1120 01:19:54.525264 17356 slave.cpp:295] Agent using credential for:
test-principal
3: I1120 01:19:54.525291 17356 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/FaultToleranceTest_ForwardStatusUpdateUnknownExecutor_j3A6NV/http_credentials'
3: W1120 01:19:54.525300 17350 process.cpp:3185] Attempted to spawn already
running process version@172.17.0.2:36246
3: I1120 01:19:54.525609 17356 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1120 01:19:54.525805 17356 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1120 01:19:54.526849 17350 sched.cpp:232] Version: 1.5.0
3: I1120 01:19:54.527669 17358 sched.cpp:336] New master detected at
master@172.17.0.2:36246
3: I1120 01:19:54.527832 17358 sched.cpp:396] Authenticating with master
master@172.17.0.2:36246
3: I1120 01:19:54.527858 17358 sched.cpp:403] Using default CRAM-MD5
authenticatee
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511138424-1892
Error response from daemon: conflict: unable to remove repository reference
"mesos-1511138424-1892" (must force) - container ef3e6d5c1e51 is using its
referenced image afef040fd12f
Build step 'Execute shell' marked build as failure
