mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4462
Date Wed, 15 Nov 2017 18:12:16 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,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)/4462/display/redirect?page=changes>

Changes:

[alexr] Added context to new agent processing calls.

------------------------------------------
[...truncated 22.01 MB...]
3: I1115 18:12:12.724629 18280 authenticatee.cpp:121] Creating new client SASL connection
3: I1115 18:12:12.724995 18287 master.cpp:8285] Authenticating slave(564)@172.17.0.4:36971
3: I1115 18:12:12.725209 18289 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1129)@172.17.0.4:36971
3: I1115 18:12:12.725632 18277 authenticator.cpp:98] Creating new server SASL connection
3: I1115 18:12:12.726037 18270 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1115 18:12:12.726080 18270 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1115 18:12:12.726213 18288 authenticator.cpp:204] Received SASL authentication start
3: I1115 18:12:12.726276 18288 authenticator.cpp:326] Authentication requires more steps
3: I1115 18:12:12.726372 18288 authenticatee.cpp:259] Received SASL authentication step
3: I1115 18:12:12.726486 18288 authenticator.cpp:232] Received SASL authentication step
3: I1115 18:12:12.726516 18288 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1115 18:12:12.726534 18288 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1115 18:12:12.726574 18288 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1115 18:12:12.726599 18288 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1115 18:12:12.726606 18288 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:12.726611 18288 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:12.726624 18288 authenticator.cpp:318] Authentication success
3: I1115 18:12:12.726764 18292 authenticatee.cpp:299] Authentication success
3: I1115 18:12:12.726797 18273 master.cpp:8315] Successfully authenticated principal 'test-principal'
at slave(564)@172.17.0.4:36971
3: I1115 18:12:12.726893 18283 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1129)@172.17.0.4:36971
3: I1115 18:12:12.727098 18282 slave.cpp:1161] Successfully authenticated with master master@172.17.0.4:36971
3: I1115 18:12:12.727380 18282 slave.cpp:1682] Will retry registration in 11.323178ms if necessary
3: I1115 18:12:12.727694 18272 master.cpp:6032] Received register agent message from slave(564)@172.17.0.4:36971
(a43abe5b75f2)
3: I1115 18:12:12.727738 18272 master.cpp:3870] Authorizing agent with principal 'test-principal'
3: I1115 18:12:12.728344 18278 master.cpp:6092] Authorized registration of agent at slave(564)@172.17.0.4:36971
(a43abe5b75f2)
3: I1115 18:12:12.728483 18278 master.cpp:6185] Registering agent at slave(564)@172.17.0.4:36971
(a43abe5b75f2) with id 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: I1115 18:12:12.728955 18290 registrar.cpp:495] Applied 1 operations in 105624ns; attempting
to update the registry
3: I1115 18:12:12.729791 18290 registrar.cpp:552] Successfully updated the registry in 753152ns
3: I1115 18:12:12.730104 18280 master.cpp:6232] Admitted agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:12.731243 18289 slave.cpp:1207] Registered with master master@172.17.0.4:36971;
given agent ID 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: I1115 18:12:12.731103 18280 master.cpp:6263] Registered agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2) 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: I1115 18:12:12.731356 18274 status_update_manager.cpp:184] Resuming sending status updates
3: I1115 18:12:12.731559 18277 hierarchical.cpp:593] Added agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
(a43abe5b75f2) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1115 18:12:12.731645 18289 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/meta/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/slave.info'
3: I1115 18:12:12.732153 18289 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1115 18:12:12.732403 18281 master.cpp:7064] Received update of agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2) with total oversubscribed resources {}
3: I1115 18:12:12.732600 18281 master.cpp:7082] Ignoring update on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2) as it reports no changes
3: I1115 18:12:12.732986 18277 hierarchical.cpp:1450] Performed allocation for 1 agents in
1.239602ms
3: I1115 18:12:12.733623 18288 master.cpp:8115] Sending 1 offers to framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
3: I1115 18:12:12.734330 18288 sched.cpp:921] Scheduler::resourceOffers took 125506ns
3: I1115 18:12:12.736460 18272 master.cpp:10024] Removing offer 413eeabe-dae7-4e8e-95c9-e521ef08655c-O0
3: I1115 18:12:12.736630 18272 master.cpp:4228] Processing ACCEPT call for offers: [ 413eeabe-dae7-4e8e-95c9-e521ef08655c-O0
] on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0 at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 (default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
3: I1115 18:12:12.736735 18272 master.cpp:3597] Authorizing framework principal 'test-principal'
to launch task a51c5d21-daba-4c26-bb60-12c40672334e
3: I1115 18:12:12.739405 18271 master.cpp:10770] Adding task a51c5d21-daba-4c26-bb60-12c40672334e
with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0 at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:12.740149 18271 master.cpp:4976] Launching task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 (default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0 at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:12.741247 18285 slave.cpp:1803] Got assigned task 'a51c5d21-daba-4c26-bb60-12c40672334e'
for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:12.742089 18285 slave.cpp:2071] Authorizing task 'a51c5d21-daba-4c26-bb60-12c40672334e'
for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:12.742156 18285 slave.cpp:7262] Authorizing framework principal 'test-principal'
to launch task a51c5d21-daba-4c26-bb60-12c40672334e
3: I1115 18:12:12.743311 18274 slave.cpp:2239] Launching task 'a51c5d21-daba-4c26-bb60-12c40672334e'
for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:12.744040 18274 paths.cpp:621] Trying to chown '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
to user 'mesos'
3: I1115 18:12:12.744329 18274 slave.cpp:7736] Launching executor 'a51c5d21-daba-4c26-bb60-12c40672334e'
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}]
in work directory '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
3: I1115 18:12:12.745467 18274 slave.cpp:2467] Queued task 'a51c5d21-daba-4c26-bb60-12c40672334e'
for executor 'a51c5d21-daba-4c26-bb60-12c40672334e' of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:12.745563 18274 slave.cpp:958] Successfully attached '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
to virtual path '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/latest'
3: I1115 18:12:12.745615 18274 slave.cpp:958] Successfully attached '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
to virtual path '/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/latest'
3: I1115 18:12:12.745656 18274 slave.cpp:958] Successfully attached '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
to virtual path '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
3: I1115 18:12:12.746085 18274 slave.cpp:2930] Launching container 83dc1311-7a2d-44f3-a514-17c94fa19be3
for executor 'a51c5d21-daba-4c26-bb60-12c40672334e' of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:12.746439 18281 containerizer.cpp:1149] Starting container 83dc1311-7a2d-44f3-a514-17c94fa19be3
3: I1115 18:12:12.746945 18281 containerizer.cpp:2818] Transitioning the state of container
83dc1311-7a2d-44f3-a514-17c94fa19be3 from PROVISIONING to PREPARING
3: I1115 18:12:12.752321 18283 containerizer.cpp:1768] Launching 'mesos-containerizer' with
flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.4:36971"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju\/slaves\/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0\/frameworks\/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000\/executors\/a51c5d21-daba-4c26-bb60-12c40672334e\/runs\/83dc1311-7a2d-44f3-a514-17c94fa19be3"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiI4M2RjMTMxMS03YTJkLTQ0ZjMtYTUxNC0xN2M5NGZhMTliZTMiLCJlaWQiOiJhNTFjNWQyMS1kYWJhLTRjMjYtYmI2MC0xMmM0MDY3MjMzNGUiLCJmaWQiOiI0MTNlZWFiZS1kYWU3LTRlOGUtOTVjOS1lNTIxZWYwODY1NWMtMDAwMCJ9.PSRAa37bSiWKz3a4Y2jXWXL344N-R5c7ji6HpSgiv2k"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"a51c5d21-daba-4c26-bb60-12c40672334e"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"413eeabe-dae7-4e8e-95c9-e521ef08655c-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"413eeabe-dae7-4e8e-95c9-e521ef08655c-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(564)@172.17.0.4:36971"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju\/slaves\/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0\/frameworks\/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000\/executors\/a51c5d21-daba-4c26-bb60-12c40672334e\/runs\/83dc1311-7a2d-44f3-a514-17c94fa19be3"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju\/slaves\/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0\/frameworks\/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000\/executors\/a51c5d21-daba-4c26-bb60-12c40672334e\/runs\/83dc1311-7a2d-44f3-a514-17c94fa19be3"}"
--pipe_read="8" --pipe_write="9" --runtime_directory="/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_mCjpPI/containers/83dc1311-7a2d-44f3-a514-17c94fa19be3"
--unshare_namespace_mnt="false"'
3: I1115 18:12:12.755125 18283 launcher.cpp:140] Forked child with pid '24246' for container
'83dc1311-7a2d-44f3-a514-17c94fa19be3'
3: I1115 18:12:12.755808 18283 containerizer.cpp:2818] Transitioning the state of container
83dc1311-7a2d-44f3-a514-17c94fa19be3 from PREPARING to ISOLATING
3: I1115 18:12:12.757550 18280 containerizer.cpp:2818] Transitioning the state of container
83dc1311-7a2d-44f3-a514-17c94fa19be3 from ISOLATING to FETCHING
3: I1115 18:12:12.757740 18280 fetcher.cpp:379] Starting to fetch URIs for container: 83dc1311-7a2d-44f3-a514-17c94fa19be3,
directory: /tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3
3: I1115 18:12:12.758584 18277 containerizer.cpp:2818] Transitioning the state of container
83dc1311-7a2d-44f3-a514-17c94fa19be3 from FETCHING to RUNNING
3: I1115 18:12:13.008285 24258 exec.cpp:162] Version: 1.5.0
3: I1115 18:12:13.021422 18282 slave.cpp:4063] Got registration for executor 'a51c5d21-daba-4c26-bb60-12c40672334e'
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 from executor(1)@172.17.0.4:36271
3: I1115 18:12:13.024224 18285 slave.cpp:2669] Sending queued task 'a51c5d21-daba-4c26-bb60-12c40672334e'
to executor 'a51c5d21-daba-4c26-bb60-12c40672334e' of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
at executor(1)@172.17.0.4:36271
3: I1115 18:12:13.026182 24267 exec.cpp:237] Executor registered on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: I1115 18:12:13.031632 24249 executor.cpp:171] Received SUBSCRIBED event
3: I1115 18:12:13.033422 24249 executor.cpp:175] Subscribed executor on a43abe5b75f2
3: I1115 18:12:13.033833 24249 executor.cpp:171] Received LAUNCH event
3: I1115 18:12:13.036955 24249 executor.cpp:637] Starting task a51c5d21-daba-4c26-bb60-12c40672334e
3: I1115 18:12:13.040567 18270 slave.cpp:4517] Handling status update TASK_STARTING (UUID:
f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e of framework
413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 from executor(1)@172.17.0.4:36271
3: I1115 18:12:13.042621 18282 status_update_manager.cpp:323] Received status update TASK_STARTING
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.042695 18282 status_update_manager.cpp:500] Creating StatusUpdate stream
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.043515 18282 status_update_manager.cpp:377] Forwarding update TASK_STARTING
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 to the agent
3: I1115 18:12:13.043817 18272 slave.cpp:4998] Forwarding the update TASK_STARTING (UUID:
f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e of framework
413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 to master@172.17.0.4:36971
3: I1115 18:12:13.044108 18272 slave.cpp:4892] Status update manager successfully handled
status update TASK_STARTING (UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.044165 18272 slave.cpp:4908] Sending acknowledgement for status update TASK_STARTING
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 to executor(1)@172.17.0.4:36271
3: I1115 18:12:13.044435 18271 master.cpp:7247] Status update TASK_STARTING (UUID: f41df3bd-24ec-4e35-a907-432dba89cc85)
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
from agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0 at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:13.044509 18271 master.cpp:7309] Forwarding status update TASK_STARTING (UUID:
f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e of framework
413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.044759 18271 master.cpp:9502] Updating the state of task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 (latest state: TASK_STARTING, status
update state: TASK_STARTING)
3: I1115 18:12:13.044984 18275 sched.cpp:1029] Scheduler::statusUpdate took 80489ns
3: I1115 18:12:13.045403 18278 master.cpp:5791] Processing ACKNOWLEDGE call f41df3bd-24ec-4e35-a907-432dba89cc85
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971 on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: I1115 18:12:13.045493 18283 slave.cpp:3438] Updating info for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.045637 18285 status_update_manager.cpp:184] Resuming sending status updates
3: W1115 18:12:13.045678 18285 status_update_manager.cpp:191] Resending status update TASK_STARTING
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.045717 18285 status_update_manager.cpp:377] Forwarding update TASK_STARTING
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 to the agent
3: I1115 18:12:13.045974 18285 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.045994 18289 slave.cpp:4998] Forwarding the update TASK_STARTING (UUID:
f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e of framework
413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 to master@172.17.0.4:36971
3: I1115 18:12:13.046252 18280 slave.cpp:3801] Status update manager successfully handled
status update acknowledgement (UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.046332 18270 master.cpp:7247] Status update TASK_STARTING (UUID: f41df3bd-24ec-4e35-a907-432dba89cc85)
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
from agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0 at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:13.046378 18270 master.cpp:7309] Forwarding status update TASK_STARTING (UUID:
f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e of framework
413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.046568 18270 master.cpp:9502] Updating the state of task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 (latest state: TASK_STARTING, status
update state: TASK_STARTING)
3: I1115 18:12:13.046763 18279 sched.cpp:1029] Scheduler::statusUpdate took 26222ns
3: I1115 18:12:13.047072 18281 master.cpp:5791] Processing ACKNOWLEDGE call f41df3bd-24ec-4e35-a907-432dba89cc85
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971 on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: I1115 18:12:13.047412 18292 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: E1115 18:12:13.047582 18277 slave.cpp:3794] Failed to handle status update acknowledgement
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000: Unexpected status update acknowledgment
(UUID: f41df3bd-24ec-4e35-a907-432dba89cc85) for task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.047751 18269 sched.cpp:2009] Asked to stop the driver
3: I1115 18:12:13.047893 18288 sched.cpp:1191] Stopping framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.048146 18291 master.cpp:8792] Processing TEARDOWN call for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
3: I1115 18:12:13.048178 18291 master.cpp:8804] Removing framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
3: I1115 18:12:13.048192 18291 master.cpp:3331] Deactivating framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
(default) at scheduler-cf477374-e524-4927-8bd2-c291f98532be@172.17.0.4:36971
3: I1115 18:12:13.048380 18273 hierarchical.cpp:412] Deactivated framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.048409 18282 slave.cpp:3267] Asked to shut down framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
by master@172.17.0.4:36971
3: I1115 18:12:13.048414 18291 master.cpp:9502] Updating the state of task a51c5d21-daba-4c26-bb60-12c40672334e
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
3: I1115 18:12:13.048439 18282 slave.cpp:3292] Shutting down framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.048506 18282 slave.cpp:5874] Shutting down executor 'a51c5d21-daba-4c26-bb60-12c40672334e'
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 at executor(1)@172.17.0.4:36271
3: I1115 18:12:13.049543 18292 containerizer.cpp:2254] Destroying container 83dc1311-7a2d-44f3-a514-17c94fa19be3
in RUNNING state
3: I1115 18:12:13.049578 18292 containerizer.cpp:2818] Transitioning the state of container
83dc1311-7a2d-44f3-a514-17c94fa19be3 from RUNNING to DESTROYING
3: I1115 18:12:13.049759 24255 exec.cpp:435] Executor asked to shutdown
3: I1115 18:12:13.049409 18291 master.cpp:9596] Removing task a51c5d21-daba-4c26-bb60-12c40672334e
with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:13.049932 18292 launcher.cpp:156] Asked to destroy container 83dc1311-7a2d-44f3-a514-17c94fa19be3
3: I1115 18:12:13.050027 18287 hierarchical.cpp:1125] Recovered cpus(allocated: *):2; mem(allocated:
*):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024;
disk:1024; ports:[31000-32000], allocated: {}) on agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
from framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.050683 18283 hierarchical.cpp:355] Removed framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.060632 18277 slave.cpp:5130] Got exited event for executor(1)@172.17.0.4:36271
3: I1115 18:12:13.074110 18273 containerizer.cpp:2705] Container 83dc1311-7a2d-44f3-a514-17c94fa19be3
has exited
3: I1115 18:12:13.076252 18274 provisioner.cpp:490] Ignoring destroy request for unknown container
83dc1311-7a2d-44f3-a514-17c94fa19be3
3: I1115 18:12:13.077106 18276 slave.cpp:5539] Executor 'a51c5d21-daba-4c26-bb60-12c40672334e'
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 terminated with signal Killed
3: I1115 18:12:13.077198 18276 slave.cpp:5643] Cleaning up executor 'a51c5d21-daba-4c26-bb60-12c40672334e'
of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000 at executor(1)@172.17.0.4:36271
3: I1115 18:12:13.077503 18273 gc.cpp:90] Scheduling '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e/runs/83dc1311-7a2d-44f3-a514-17c94fa19be3'
for gc 6.99999910410963days in the future
3: I1115 18:12:13.077700 18276 slave.cpp:5750] Cleaning up framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.077738 18273 gc.cpp:90] Scheduling '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000/executors/a51c5d21-daba-4c26-bb60-12c40672334e'
for gc 6.99999910162074days in the future
3: I1115 18:12:13.077808 18289 status_update_manager.cpp:285] Closing status update streams
for framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.077862 18289 status_update_manager.cpp:531] Cleaning up status update stream
for task a51c5d21-daba-4c26-bb60-12c40672334e of framework 413eeabe-dae7-4e8e-95c9-e521ef08655c-0000
3: I1115 18:12:13.077917 18276 slave.cpp:883] Agent terminating
3: I1115 18:12:13.077915 18273 gc.cpp:90] Scheduling '/tmp/SlaveRecoveryTest_0_NonCheckpointingFramework_rtT4ju/slaves/413eeabe-dae7-4e8e-95c9-e521ef08655c-S0/frameworks/413eeabe-dae7-4e8e-95c9-e521ef08655c-0000'
for gc 6.99999909954963days in the future
3: I1115 18:12:13.078117 18287 master.cpp:1309] Agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2) disconnected
3: I1115 18:12:13.078143 18287 master.cpp:3368] Disconnecting agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:13.078220 18287 master.cpp:3387] Deactivating agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
at slave(564)@172.17.0.4:36971 (a43abe5b75f2)
3: I1115 18:12:13.078335 18281 hierarchical.cpp:690] Agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
deactivated
3: I1115 18:12:13.085058 18269 master.cpp:1151] Master terminating
3: I1115 18:12:13.085867 18285 hierarchical.cpp:626] Removed agent 413eeabe-dae7-4e8e-95c9-e521ef08655c-S0
3: [       OK ] SlaveRecoveryTest/0.NonCheckpointingFramework (405 ms)
3: [ RUN      ] SlaveRecoveryTest/0.KillTask
3: I1115 18:12:13.094202 18269 cluster.cpp:162] Creating default 'local' authorizer
3: I1115 18:12:13.097543 18272 master.cpp:448] Master 872d726b-9de8-4aca-abc1-ac6865087476
(a43abe5b75f2) started on 172.17.0.4:36971
3: I1115 18:12:13.097585 18272 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/BFJ6DU/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/BFJ6DU/master"
--zk_session_timeout="10secs"
3: I1115 18:12:13.097950 18272 master.cpp:499] Master only allowing authenticated frameworks
to register
3: I1115 18:12:13.097957 18272 master.cpp:505] Master only allowing authenticated agents to
register
3: I1115 18:12:13.097961 18272 master.cpp:511] Master only allowing authenticated HTTP frameworks
to register
3: I1115 18:12:13.097966 18272 credentials.hpp:37] Loading credentials for authentication
from '/tmp/BFJ6DU/credentials'
3: I1115 18:12:13.098321 18272 master.cpp:555] Using default 'crammd5' authenticator
3: I1115 18:12:13.098510 18272 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1115 18:12:13.098701 18272 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1115 18:12:13.098829 18272 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1115 18:12:13.098948 18272 master.cpp:634] Authorization enabled
3: I1115 18:12:13.099186 18280 whitelist_watcher.cpp:77] No whitelist given
3: I1115 18:12:13.099273 18289 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1115 18:12:13.102898 18279 master.cpp:2213] Elected as the leading master!
3: I1115 18:12:13.102924 18279 master.cpp:1693] Recovering from registrar
3: I1115 18:12:13.103076 18292 registrar.cpp:347] Recovering registrar
3: I1115 18:12:13.103823 18292 registrar.cpp:391] Successfully fetched the registry (0B) in
709888ns
3: I1115 18:12:13.103982 18292 registrar.cpp:495] Applied 1 operations in 57042ns; attempting
to update the registry
3: I1115 18:12:13.104606 18292 registrar.cpp:552] Successfully updated the registry in 555008ns
3: I1115 18:12:13.104728 18292 registrar.cpp:424] Successfully recovered registrar
3: I1115 18:12:13.105134 18270 master.cpp:1806] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
3: I1115 18:12:13.105201 18290 hierarchical.cpp:209] Skipping recovery of hierarchical allocator:
nothing to recover
3: I1115 18:12:13.111019 18269 containerizer.cpp:301] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1115 18:12:13.111968 18269 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1115 18:12:13.112159 18269 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1115 18:12:13.112213 18269 provisioner.cpp:255] Using default backend 'copy'
3: W1115 18:12:13.119117 18269 process.cpp:3178] Attempted to spawn already running process
files@172.17.0.4:36971
3: I1115 18:12:13.119616 18269 cluster.cpp:448] Creating default 'local' authorizer
3: I1115 18:12:13.122700 18289 slave.cpp:262] Mesos agent started on (565)@172.17.0.4:36971
3: I1115 18:12:13.122756 18289 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/SlaveRecoveryTest_0_KillTask_9syNBp/store/appc" --authenticate_http_executors="true"
--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/SlaveRecoveryTest_0_KillTask_9syNBp/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/SlaveRecoveryTest_0_KillTask_9syNBp/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_secret_key="/tmp/SlaveRecoveryTest_0_KillTask_9syNBp/executor_secret_key" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/SlaveRecoveryTest_0_KillTask_9syNBp/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/SlaveRecoveryTest_0_KillTask_9syNBp/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/SlaveRecoveryTest_0_KillTask_9syNBp" --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/SlaveRecoveryTest_0_KillTask_EDUV79" --zk_session_timeout="10secs"
3: I1115 18:12:13.123466 18289 credentials.hpp:86] Loading credential for authentication from
'/tmp/SlaveRecoveryTest_0_KillTask_9syNBp/credential'
3: I1115 18:12:13.123703 18289 slave.cpp:295] Agent using credential for: test-principal
3: I1115 18:12:13.123728 18289 credentials.hpp:37] Loading credentials for authentication
from '/tmp/SlaveRecoveryTest_0_KillTask_9syNBp/http_credentials'
3: W1115 18:12:13.123771 18269 process.cpp:3178] Attempted to spawn already running process
version@172.17.0.4:36971
3: I1115 18:12:13.124184 18289 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-executor'
3: I1115 18:12:13.124356 18289 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-executor'
3: I1115 18:12:13.124706 18289 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1115 18:12:13.124876 18289 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readonly'
3: I1115 18:12:13.124918 18269 sched.cpp:232] Version: 1.5.0
3: I1115 18:12:13.125170 18289 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1115 18:12:13.125318 18289 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readwrite'
3: I1115 18:12:13.125751 18280 sched.cpp:336] New master detected at master@172.17.0.4:36971
3: I1115 18:12:13.125962 18280 sched.cpp:396] Authenticating with master master@172.17.0.4:36971
3: I1115 18:12:13.125986 18280 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1115 18:12:13.126385 18282 authenticatee.cpp:121] Creating new client SASL connection
3: I1115 18:12:13.126817 18291 master.cpp:8285] Authenticating scheduler-968f94d3-c434-48d8-a8b5-3949f760ac1e@172.17.0.4:36971
3: I1115 18:12:13.126986 18278 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1130)@172.17.0.4:36971
3: I1115 18:12:13.127336 18272 authenticator.cpp:98] Creating new server SASL connection
3: I1115 18:12:13.127696 18283 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1115 18:12:13.127748 18283 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1115 18:12:13.127526 18289 slave.cpp:593] 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: I1115 18:12:13.127876 18289 slave.cpp:601] Agent attributes: [  ]
3: I1115 18:12:13.127889 18289 slave.cpp:610] Agent hostname: a43abe5b75f2
3: I1115 18:12:13.127908 18283 authenticator.cpp:204] Received SASL authentication start
3: I1115 18:12:13.127991 18283 authenticator.cpp:326] Authentication requires more steps
3: I1115 18:12:13.128126 18285 status_update_manager.cpp:177] Pausing sending status updates
3: I1115 18:12:13.128201 18273 authenticatee.cpp:259] Received SASL authentication step
3: I1115 18:12:13.128381 18274 authenticator.cpp:232] Received SASL authentication step
3: I1115 18:12:13.128419 18274 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1115 18:12:13.128437 18274 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1115 18:12:13.128494 18274 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1115 18:12:13.128525 18274 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1115 18:12:13.128537 18274 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:13.128543 18274 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:13.128561 18274 authenticator.cpp:318] Authentication success
3: I1115 18:12:13.128664 18286 authenticatee.cpp:299] Authentication success
3: I1115 18:12:13.128782 18276 master.cpp:8315] Successfully authenticated principal 'test-principal'
at scheduler-968f94d3-c434-48d8-a8b5-3949f760ac1e@172.17.0.4:36971
3: I1115 18:12:13.128888 18271 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1130)@172.17.0.4:36971
3: I1115 18:12:13.129161 18287 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:36971
3: I1115 18:12:13.129185 18287 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:36971
3: I1115 18:12:13.129338 18287 sched.cpp:857] Will retry registration in 1.763329663secs if
necessary
3: I1115 18:12:13.129583 18270 master.cpp:2961] Received SUBSCRIBE call for framework 'default'
at scheduler-968f94d3-c434-48d8-a8b5-3949f760ac1e@172.17.0.4:36971
3: I1115 18:12:13.129613 18270 master.cpp:2278] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1115 18:12:13.129976 18290 state.cpp:64] Recovering state from '/tmp/SlaveRecoveryTest_0_KillTask_EDUV79/meta'
3: I1115 18:12:13.130254 18288 master.cpp:3041] Subscribing framework default with checkpointing
enabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1115 18:12:13.130282 18282 status_update_manager.cpp:203] Recovering status update manager
3: I1115 18:12:13.130465 18272 containerizer.cpp:662] Recovering containerizer
3: I1115 18:12:13.131170 18292 sched.cpp:751] Framework registered with 872d726b-9de8-4aca-abc1-ac6865087476-0000
3: I1115 18:12:13.131235 18279 hierarchical.cpp:303] Added framework 872d726b-9de8-4aca-abc1-ac6865087476-0000
3: I1115 18:12:13.131252 18292 sched.cpp:765] Scheduler::registered took 57011ns
3: I1115 18:12:13.131515 18279 hierarchical.cpp:1450] Performed allocation for 0 agents in
101512ns
3: I1115 18:12:13.132171 18289 provisioner.cpp:416] Provisioner recovery complete
3: I1115 18:12:13.132509 18284 slave.cpp:6441] Finished recovery
3: I1115 18:12:13.133294 18291 status_update_manager.cpp:177] Pausing sending status updates
3: I1115 18:12:13.133327 18282 slave.cpp:1007] New master detected at master@172.17.0.4:36971
3: I1115 18:12:13.133427 18282 slave.cpp:1042] Detecting new master
3: I1115 18:12:13.140743 18277 slave.cpp:1069] Authenticating with master master@172.17.0.4:36971
3: I1115 18:12:13.140805 18277 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1115 18:12:13.141187 18274 authenticatee.cpp:121] Creating new client SASL connection
3: I1115 18:12:13.141798 18288 master.cpp:8285] Authenticating slave(565)@172.17.0.4:36971
3: I1115 18:12:13.141953 18273 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1131)@172.17.0.4:36971
3: I1115 18:12:13.142233 18286 authenticator.cpp:98] Creating new server SASL connection
3: I1115 18:12:13.142527 18285 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1115 18:12:13.142571 18285 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1115 18:12:13.142676 18285 authenticator.cpp:204] Received SASL authentication start
3: I1115 18:12:13.142737 18285 authenticator.cpp:326] Authentication requires more steps
3: I1115 18:12:13.142845 18272 authenticatee.cpp:259] Received SASL authentication step
3: I1115 18:12:13.142962 18292 authenticator.cpp:232] Received SASL authentication step
3: I1115 18:12:13.143005 18292 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1115 18:12:13.143019 18292 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1115 18:12:13.143059 18292 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1115 18:12:13.143077 18292 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'a43abe5b75f2' server FQDN: 'a43abe5b75f2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1115 18:12:13.143087 18292 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:13.143092 18292 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1115 18:12:13.143105 18292 authenticator.cpp:318] Authentication success
3: I1115 18:12:13.143201 18287 authenticatee.cpp:299] Authentication success
3: I1115 18:12:13.143252 18271 master.cpp:8315] Successfully authenticated principal 'test-principal'
at slave(565)@172.17.0.4:36971
3: I1115 18:12:13.143301 18292 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1131)@172.17.0.4:36971
3: I1115 18:12:13.143601 18281 slave.cpp:1161] Successfully authenticated with master master@172.17.0.4:36971
3: I1115 18:12:13.143970 18281 slave.cpp:1682] Will retry registration in 4.594957ms if necessary
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1510767993-20890
Build step 'Execute shell' marked build as failure


Mime
View raw message