See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4066/display/redirect?page=changes>
Changes:
[alexr] Logged when an offer is removed.
[alexr] Fixed slaves_unreachable metric.
[alexr] Added missing metrics to tests.
[alexr] Adjusted the style in metrics tests for consistency.
------------------------------------------
[...truncated 25.32 MB...]
I0804 12:34:00.244276 1301 hierarchical.cpp:593] Added agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 (114d4efc6580) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I0804 12:34:00.244487 1299 slave.cpp:1194] Checkpointing SlaveInfo to
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/meta/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/slave.info'
I0804 12:34:00.245607 1301 hierarchical.cpp:2015] No inverse offers to send
out!
I0804 12:34:00.245666 1301 hierarchical.cpp:1468] Performed allocation for 1
agents in 1.241171ms
I0804 12:34:00.245734 1301 status_update_manager.cpp:184] Resuming sending
status updates
I0804 12:34:00.246523 1301 master.cpp:7667] Sending 1 offers to framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487
I0804 12:34:00.247076 1305 sched.cpp:933] Scheduler::resourceOffers took
111495ns
I0804 12:34:00.247486 1299 slave.cpp:1232] Forwarding total oversubscribed
resources {}
I0804 12:34:00.247611 1299 slave.cpp:4883] Received ping from
slave-observer(624)@172.17.0.3:40487
I0804 12:34:00.247687 1300 master.cpp:6688] Received update of agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580) with total oversubscribed resources {}
I0804 12:34:00.247947 1298 hierarchical.cpp:660] Agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 (114d4efc6580) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0804 12:34:00.250113 1300 master.cpp:9164] Removing offer
cbbc9c57-149f-450d-901e-55d17703066b-O0
I0804 12:34:00.250246 1300 master.cpp:4153] Processing ACCEPT call for offers:
[ cbbc9c57-149f-450d-901e-55d17703066b-O0 ] on agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580) for framework cbbc9c57-149f-450d-901e-55d17703066b-0000
(default) at [email protected]:40487
I0804 12:34:00.250458 1300 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task 299257af-6f53-42a1-84fc-9711f272d5ec
I0804 12:34:00.252882 1300 master.cpp:9724] Adding task
299257af-6f53-42a1-84fc-9711f272d5ec 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 cbbc9c57-149f-450d-901e-55d17703066b-S0 at
slave(673)@172.17.0.3:40487 (114d4efc6580)
I0804 12:34:00.253466 1300 master.cpp:4816] Launching task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487 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 cbbc9c57-149f-450d-901e-55d17703066b-S0 at
slave(673)@172.17.0.3:40487 (114d4efc6580)
I0804 12:34:00.254396 1301 slave.cpp:1697] Got assigned task
'299257af-6f53-42a1-84fc-9711f272d5ec' for framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.255161 1301 slave.cpp:1978] Authorizing task
'299257af-6f53-42a1-84fc-9711f272d5ec' for framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.255215 1301 slave.cpp:6706] Authorizing framework principal
'test-principal' to launch task 299257af-6f53-42a1-84fc-9711f272d5ec
I0804 12:34:00.256268 1299 slave.cpp:2165] Launching task
'299257af-6f53-42a1-84fc-9711f272d5ec' for framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.256876 1299 paths.cpp:578] Trying to chown
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255'
to user 'mesos'
I0804 12:34:00.257167 1299 slave.cpp:7162] Launching executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-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/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255'
I0804 12:34:00.257877 1305 hierarchical.cpp:887] Updated allocation of
framework cbbc9c57-149f-450d-901e-55d17703066b-0000 on agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 from cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024;
disk(allocated: *):1024; ports(allocated: *):[31000-32000]
I0804 12:34:00.258282 1299 slave.cpp:2394] Queued task
'299257af-6f53-42a1-84fc-9711f272d5ec' for executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.258355 1299 slave.cpp:924] Successfully attached file
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255'
I0804 12:34:00.258383 1299 slave.cpp:924] Successfully attached file
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255'
I0804 12:34:00.258782 1299 slave.cpp:2852] Launching container
f062300b-a38f-41a0-b9d2-f4c4f67f4255 for executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.259224 1304 containerizer.cpp:1072] Starting container
f062300b-a38f-41a0-b9d2-f4c4f67f4255
I0804 12:34:00.259757 1304 containerizer.cpp:2627] Transitioning the state of
container f062300b-a38f-41a0-b9d2-f4c4f67f4255 from PROVISIONING to PREPARING
I0804 12:34:00.270905 1298 containerizer.cpp:1646] 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.3:40487"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiJmMDYyMzAwYi1hMzhmLTQxYTAtYjlkMi1mNGM0ZjY3ZjQyNTUiLCJlaWQiOiIyOTkyNTdhZi02ZjUzLTQyYTEtODRmYy05NzExZjI3MmQ1ZWMiLCJmaWQiOiJjYmJjOWM1Ny0xNDlmLTQ1MGQtOTAxZS01NWQxNzcwMzA2NmItMDAwMCJ9.ONZvUqgw4fRX-tCQ3CGKYuGQGKYciMDlqh6juou5ifs"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"299257af-6f53-42a1-84fc-9711f272d5ec"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"cbbc9c57-149f-450d-901e-55d17703066b-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"cbbc9c57-149f-450d-901e-55d17703066b-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(673)@172.17.0.3:40487"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"}"
--pipe_read="9" --pipe_write="10"
--runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_81lFkf/containers/f062300b-a38f-41a0-b9d2-f4c4f67f4255"
--unshare_namespace_mnt="false"'
I0804 12:34:00.274518 1298 launcher.cpp:140] Forked child with pid '4896' for
container 'f062300b-a38f-41a0-b9d2-f4c4f67f4255'
I0804 12:34:00.275079 1298 containerizer.cpp:2627] Transitioning the state of
container f062300b-a38f-41a0-b9d2-f4c4f67f4255 from PREPARING to ISOLATING
I0804 12:34:00.278579 1298 containerizer.cpp:2627] Transitioning the state of
container f062300b-a38f-41a0-b9d2-f4c4f67f4255 from ISOLATING to FETCHING
I0804 12:34:00.279099 1304 fetcher.cpp:379] Starting to fetch URIs for
container: f062300b-a38f-41a0-b9d2-f4c4f67f4255, directory:
/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255
I0804 12:34:00.280016 1302 containerizer.cpp:2627] Transitioning the state of
container f062300b-a38f-41a0-b9d2-f4c4f67f4255 from FETCHING to RUNNING
I0804 12:34:00.442515 1304 slave.cpp:3877] Got registration for executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 from executor(1)@172.17.0.3:41983
I0804 12:34:00.446746 1305 slave.cpp:2599] Sending queued task
'299257af-6f53-42a1-84fc-9711f272d5ec' to executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 at executor(1)@172.17.0.3:41983
I0804 12:34:00.467423 1303 slave.cpp:4341] Handling status update TASK_RUNNING
(UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 from executor(1)@172.17.0.3:41983
I0804 12:34:00.469769 1300 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.470095 1300 status_update_manager.cpp:500] Creating
StatusUpdate stream for task 299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.471593 1300 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 to the agent
I0804 12:34:00.472280 1298 slave.cpp:4793] Forwarding the update TASK_RUNNING
(UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 to [email protected]:40487
I0804 12:34:00.472488 1298 slave.cpp:4687] Status update manager successfully
handled status update TASK_RUNNING (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3)
for task 299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.472544 1298 slave.cpp:4703] Sending acknowledgement for status
update TASK_RUNNING (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 to executor(1)@172.17.0.3:41983
I0804 12:34:00.473193 1300 master.cpp:6846] Status update TASK_RUNNING (UUID:
9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 from agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580)
I0804 12:34:00.473573 1300 master.cpp:6908] Forwarding status update
TASK_RUNNING (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.475397 1300 master.cpp:8933] Updating the state of task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
I0804 12:34:00.475898 1299 sched.cpp:1041] Scheduler::statusUpdate took
309082ns
I0804 12:34:00.476572 1299 master.cpp:5477] Processing ACKNOWLEDGE call
9d0a674c-97c5-4e5b-a9d4-9d3f807afac3 for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487 on agent
cbbc9c57-149f-450d-901e-55d17703066b-S0
I0804 12:34:00.477205 1301 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: 9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.477478 1303 slave.cpp:3605] Status update manager successfully
handled status update acknowledgement (UUID:
9d0a674c-97c5-4e5b-a9d4-9d3f807afac3) for task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.480527 1301 process.cpp:3933] Handling HTTP event for process
'slave(673)' with path: '/slave(673)/api/v1'
I0804 12:34:00.482720 1300 http.cpp:1166] HTTP POST for /slave(673)/api/v1
from 172.17.0.3:42122
I0804 12:34:00.483665 1300 http.cpp:532] Processing call
LAUNCH_NESTED_CONTAINER_SESSION
I0804 12:34:00.485082 1301 containerizer.cpp:1043] Trying to chown
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255/containers/32954012-abb3-4d26-bbce-47874008443d'
to user 'mesos'
I0804 12:34:00.485306 1301 containerizer.cpp:1072] Starting container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.486910 1301 containerizer.cpp:2627] Transitioning the state of
container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d from
PROVISIONING to PREPARING
I0804 12:34:00.489526 1302 switchboard.cpp:545] Launching
'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false"
--socket_address="/tmp/mesos-io-switchboard-a2de54e3-0f89-417c-b9f2-bed46d5d4402"
--stderr_from_fd="19" --stderr_to_fd="2" --stdin_to_fd="16"
--stdout_from_fd="17" --stdout_to_fd="1" --tty="false"
--wait_for_connection="true"' for container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.493504 1302 switchboard.cpp:575] Created I/O switchboard server
(pid: 4908) listening on socket file
'/tmp/mesos-io-switchboard-a2de54e3-0f89-417c-b9f2-bed46d5d4402' for container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.497802 1301 containerizer.cpp:1646] Launching
'mesos-containerizer' with flags '--help="false"
--launch_info="{"command":{"shell":true,"value":"printf output && printf error
1>&2"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.3:40487"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiJmMDYyMzAwYi1hMzhmLTQxYTAtYjlkMi1mNGM0ZjY3ZjQyNTUiLCJlaWQiOiIyOTkyNTdhZi02ZjUzLTQyYTEtODRmYy05NzExZjI3MmQ1ZWMiLCJmaWQiOiJjYmJjOWM1Ny0xNDlmLTQ1MGQtOTAxZS01NWQxNzcwMzA2NmItMDAwMCJ9.ONZvUqgw4fRX-tCQ3CGKYuGQGKYciMDlqh6juou5ifs"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"299257af-6f53-42a1-84fc-9711f272d5ec"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"cbbc9c57-149f-450d-901e-55d17703066b-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"cbbc9c57-149f-450d-901e-55d17703066b-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(673)@172.17.0.3:40487"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO\/slaves\/cbbc9c57-149f-450d-901e-55d17703066b-S0\/frameworks\/cbbc9c57-149f-450d-901e-55d17703066b-0000\/executors\/299257af-6f53-42a1-84fc-9711f272d5ec\/runs\/f062300b-a38f-41a0-b9d2-f4c4f67f4255"}"
--pipe_read="16" --pipe_write="17"
--runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_81lFkf/containers/f062300b-a38f-41a0-b9d2-f4c4f67f4255/containers/32954012-abb3-4d26-bbce-47874008443d"
--unshare_namespace_mnt="false"'
I0804 12:34:00.502075 1301 launcher.cpp:140] Forked child with pid '4909' for
container
'f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d'
I0804 12:34:00.503175 1301 containerizer.cpp:2627] Transitioning the state of
container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d from
PREPARING to ISOLATING
I0804 12:34:00.505363 1301 containerizer.cpp:2627] Transitioning the state of
container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d from
ISOLATING to FETCHING
I0804 12:34:00.505550 1301 fetcher.cpp:379] Starting to fetch URIs for
container:
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d,
directory:
/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255/containers/32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.508000 1298 containerizer.cpp:2627] Transitioning the state of
container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d from
FETCHING to RUNNING
I0804 12:34:00.619593 1299 http.cpp:2848] Received EOF attach response for
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.620044 1305 containerizer.cpp:2121] Destroying container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d in
RUNNING state
I0804 12:34:00.620117 1305 containerizer.cpp:2627] Transitioning the state of
container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d from
RUNNING to DESTROYING
W0804 12:34:00.620678 1303 http.cpp:2863] Launch nested container session
connection for container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d closed
I0804 12:34:00.621070 1305 launcher.cpp:156] Asked to destroy container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.622650 1297 sched.cpp:2021] Asked to stop the driver
I0804 12:34:00.623333 1304 sched.cpp:1203] Stopping framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.623869 1302 master.cpp:8348] Processing TEARDOWN call for
framework cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487
I0804 12:34:00.623921 1302 master.cpp:8360] Removing framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487
I0804 12:34:00.623934 1302 master.cpp:3264] Deactivating framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (default) at
[email protected]:40487
I0804 12:34:00.624462 1301 hierarchical.cpp:412] Deactivated framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.624541 1302 master.cpp:8933] Updating the state of task
299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0804 12:34:00.625260 1300 slave.cpp:3155] Asked to shut down framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 by [email protected]:40487
I0804 12:34:00.626910 1300 slave.cpp:3180] Shutting down framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.627425 1300 slave.cpp:5648] Shutting down executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 at executor(1)@172.17.0.3:41983
I0804 12:34:00.625870 1302 master.cpp:9027] Removing task
299257af-6f53-42a1-84fc-9711f272d5ec 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 cbbc9c57-149f-450d-901e-55d17703066b-0000 on agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580)
I0804 12:34:00.629117 1302 containerizer.cpp:2121] Destroying container
f062300b-a38f-41a0-b9d2-f4c4f67f4255 in RUNNING state
I0804 12:34:00.629140 1302 containerizer.cpp:2627] Transitioning the state of
container f062300b-a38f-41a0-b9d2-f4c4f67f4255 from RUNNING to DESTROYING
I0804 12:34:00.631122 1304 hierarchical.cpp:1152] 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 cbbc9c57-149f-450d-901e-55d17703066b-S0 from framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.632349 1304 hierarchical.cpp:355] Removed framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.676368 1301 switchboard.cpp:888] I/O switchboard server process
for container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d has
terminated (status=0)
I0804 12:34:00.677512 1302 containerizer.cpp:2527] Container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d has
exited
I0804 12:34:00.687479 1299 provisioner.cpp:490] Ignoring destroy request for
unknown container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.687940 1298 containerizer.cpp:2400] Checkpointing termination
state to nested container's runtime directory
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_81lFkf/containers/f062300b-a38f-41a0-b9d2-f4c4f67f4255/containers/32954012-abb3-4d26-bbce-47874008443d/termination'
I0804 12:34:00.689047 1298 launcher.cpp:156] Asked to destroy container
f062300b-a38f-41a0-b9d2-f4c4f67f4255
I0804 12:34:00.701576 1299 slave.cpp:4925] Got exited event for
executor(1)@172.17.0.3:41983
I0804 12:34:00.782649 1303 containerizer.cpp:2527] Container
f062300b-a38f-41a0-b9d2-f4c4f67f4255 has exited
I0804 12:34:00.785979 1300 provisioner.cpp:490] Ignoring destroy request for
unknown container f062300b-a38f-41a0-b9d2-f4c4f67f4255
I0804 12:34:00.787240 1299 slave.cpp:5326] Executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 terminated with signal Killed
I0804 12:34:00.787542 1299 slave.cpp:5426] Cleaning up executor
'299257af-6f53-42a1-84fc-9711f272d5ec' of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000 at executor(1)@172.17.0.3:41983
I0804 12:34:00.788089 1300 gc.cpp:59] Scheduling
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec/runs/f062300b-a38f-41a0-b9d2-f4c4f67f4255'
for gc 6.99999087990815days in the future
I0804 12:34:00.788493 1299 slave.cpp:5522] Cleaning up framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.788498 1303 gc.cpp:59] Scheduling
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000/executors/299257af-6f53-42a1-84fc-9711f272d5ec'
for gc 6.99999087526519days in the future
I0804 12:34:00.788926 1302 status_update_manager.cpp:285] Closing status
update streams for framework cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.789067 1302 status_update_manager.cpp:531] Cleaning up status
update stream for task 299257af-6f53-42a1-84fc-9711f272d5ec of framework
cbbc9c57-149f-450d-901e-55d17703066b-0000
I0804 12:34:00.789279 1301 gc.cpp:59] Scheduling
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_erbDkO/slaves/cbbc9c57-149f-450d-901e-55d17703066b-S0/frameworks/cbbc9c57-149f-450d-901e-55d17703066b-0000'
for gc 6.9999908680563days in the future
W0804 12:34:00.789782 1300 containerizer.cpp:2109] Attempted to destroy
unknown container
f062300b-a38f-41a0-b9d2-f4c4f67f4255.32954012-abb3-4d26-bbce-47874008443d
I0804 12:34:00.790930 1297 slave.cpp:843] Agent terminating
I0804 12:34:00.791180 1301 master.cpp:1318] Agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580) disconnected
I0804 12:34:00.791208 1301 master.cpp:3301] Disconnecting agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580)
I0804 12:34:00.791291 1301 master.cpp:3320] Deactivating agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 at slave(673)@172.17.0.3:40487
(114d4efc6580)
I0804 12:34:00.791409 1303 hierarchical.cpp:690] Agent
cbbc9c57-149f-450d-901e-55d17703066b-S0 deactivated
I0804 12:34:00.802067 1297 master.cpp:1160] Master terminating
I0804 12:34:00.802839 1303 hierarchical.cpp:626] Removed agent
cbbc9c57-149f-450d-901e-55d17703066b-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/0 (648 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSession/1
I0804 12:34:00.817317 1297 cluster.cpp:162] Creating default 'local' authorizer
I0804 12:34:00.820935 1304 master.cpp:442] Master
ba6e6c3c-2960-422e-bc65-2bd2d448446a (114d4efc6580) started on 172.17.0.3:40487
I0804 12:34:00.821115 1304 master.cpp:444] 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/uDXBEa/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/uDXBEa/master"
--zk_session_timeout="10secs"
I0804 12:34:00.821585 1304 master.cpp:494] Master only allowing authenticated
frameworks to register
I0804 12:34:00.821718 1304 master.cpp:508] Master only allowing authenticated
agents to register
I0804 12:34:00.821882 1304 master.cpp:521] Master only allowing authenticated
HTTP frameworks to register
I0804 12:34:00.822167 1304 credentials.hpp:37] Loading credentials for
authentication from '/tmp/uDXBEa/credentials'
I0804 12:34:00.822700 1304 master.cpp:566] Using default 'crammd5'
authenticator
I0804 12:34:00.823145 1304 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0804 12:34:00.823583 1304 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0804 12:34:00.823882 1304 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0804 12:34:00.824280 1304 master.cpp:646] Authorization enabled
I0804 12:34:00.824504 1305 hierarchical.cpp:171] Initialized hierarchical
allocator process
I0804 12:34:00.824731 1300 whitelist_watcher.cpp:77] No whitelist given
I0804 12:34:00.827972 1298 master.cpp:2163] Elected as the leading master!
I0804 12:34:00.828011 1298 master.cpp:1702] Recovering from registrar
I0804 12:34:00.828447 1305 registrar.cpp:347] Recovering registrar
I0804 12:34:00.829344 1305 registrar.cpp:391] Successfully fetched the
registry (0B) in 852992ns
I0804 12:34:00.829479 1305 registrar.cpp:495] Applied 1 operations in 31946ns;
attempting to update the registry
I0804 12:34:00.831499 1305 registrar.cpp:552] Successfully updated the
registry in 1.915904ms
I0804 12:34:00.831696 1305 registrar.cpp:424] Successfully recovered registrar
I0804 12:34:00.832631 1304 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
I0804 12:34:00.832609 1301 master.cpp:1801] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0804 12:34:00.838704 1297 containerizer.cpp:246] Using isolation:
posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0804 12:34:00.839450 1297 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
W0804 12:34:00.839617 1297 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I0804 12:34:00.839654 1297 provisioner.cpp:255] Using default backend 'copy'
I0804 12:34:00.845854 1297 process.cpp:3228] Attempting to spawn already
spawned process [email protected]:40487
I0804 12:34:00.846161 1297 cluster.cpp:448] Creating default 'local' authorizer
I0804 12:34:00.848610 1299 slave.cpp:250] Mesos agent started on
(674)@172.17.0.3:40487
I0804 12:34:00.848636 1299 slave.cpp:251] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/credential"
--default_role="*" --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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6"
--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/ContentType_AgentAPITest_LaunchNestedContainerSession_1_t4cOuD"
I0804 12:34:00.849129 1299 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/credential'
I0804 12:34:00.849354 1299 slave.cpp:283] Agent using credential for:
test-principal
I0804 12:34:00.849376 1299 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_Nwo2k6/http_credentials'
I0804 12:34:00.849344 1297 process.cpp:3228] Attempting to spawn already
spawned process [email protected]:40487
I0804 12:34:00.849740 1299 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
I0804 12:34:00.850040 1299 http.cpp:1047] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
I0804 12:34:00.850422 1299 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0804 12:34:00.850589 1299 http.cpp:1047] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I0804 12:34:00.850909 1299 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0804 12:34:00.851004 1297 sched.cpp:232] Version: 1.4.0
I0804 12:34:00.851042 1299 http.cpp:1047] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0804 12:34:00.851827 1305 sched.cpp:336] New master detected at
[email protected]:40487
I0804 12:34:00.851955 1305 sched.cpp:407] Authenticating with master
[email protected]:40487
I0804 12:34:00.851976 1305 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0804 12:34:00.852442 1304 authenticatee.cpp:121] Creating new client SASL
connection
I0804 12:34:00.853118 1301 master.cpp:7837] Authenticating
[email protected]:40487
I0804 12:34:00.853279 1303 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1288)@172.17.0.3:40487
I0804 12:34:00.853749 1303 authenticator.cpp:98] Creating new server SASL
connection
I0804 12:34:00.854753 1298 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0804 12:34:00.854785 1298 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0804 12:34:00.855038 1305 authenticator.cpp:204] Received SASL authentication
start
I0804 12:34:00.855104 1305 authenticator.cpp:326] Authentication requires more
steps
I0804 12:34:00.855274 1305 authenticatee.cpp:259] Received SASL authentication
step
I0804 12:34:00.855396 1305 authenticator.cpp:232] Received SASL authentication
step
I0804 12:34:00.855479 1305 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '114d4efc6580' server FQDN: '114d4efc6580'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0804 12:34:00.855492 1305 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0804 12:34:00.855535 1305 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0804 12:34:00.855551 1305 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '114d4efc6580' server FQDN: '114d4efc6580'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0804 12:34:00.855557 1305 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0804 12:34:00.855562 1305 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0804 12:34:00.855574 1305 authenticator.cpp:318] Authentication success
I0804 12:34:00.855659 1301 authenticatee.cpp:299] Authentication success
I0804 12:34:00.855770 1303 master.cpp:7867] Successfully authenticated
principal 'test-principal' at
[email protected]:40487
I0804 12:34:00.855955 1298 sched.cpp:513] Successfully authenticated with
master [email protected]:40487
I0804 12:34:00.855978 1298 sched.cpp:836] Sending SUBSCRIBE call to
[email protected]:40487
I0804 12:34:00.856103 1298 sched.cpp:869] Will retry registration in
225.165378ms if necessary
*** Aborted at 1501850040 (unix time) try "date -d @1501850040" if you are
using GNU date ***
I0804 12:34:00.856456 1304 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1288)@172.17.0.3:40487
I0804 12:34:00.856755 1302 master.cpp:2894] Received SUBSCRIBE call for
framework 'default' at
[email protected]:40487
I0804 12:34:00.856942 1302 master.cpp:2228] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0804 12:34:00.857589 1303 master.cpp:2974] Subscribing framework default with
checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0804 12:34:00.858248 1304 sched.cpp:759] Framework registered with
ba6e6c3c-2960-422e-bc65-2bd2d448446a-0000
I0804 12:34:00.858714 1305 hierarchical.cpp:303] Added framework
ba6e6c3c-2960-422e-bc65-2bd2d448446a-0000
I0804 12:34:00.858662 1304 sched.cpp:773] Scheduler::registered took 363204ns
I0804 12:34:00.858975 1305 hierarchical.cpp:1925] No allocations performed
I0804 12:34:00.859184 1305 hierarchical.cpp:2015] No inverse offers to send
out!
I0804 12:34:00.859225 1305 hierarchical.cpp:1468] Performed allocation for 0
agents in 358712ns
I0804 12:34:00.855490 1299 slave.cpp:565] 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"}]
I0804 12:34:00.860662 1299 slave.cpp:573] Agent attributes: [ ]
I0804 12:34:00.860766 1299 slave.cpp:582] Agent hostname: 114d4efc6580
I0804 12:34:00.860940 1303 status_update_manager.cpp:177] Pausing sending
status updates
PC: @ 0x2b870b2ea068 process::EventQueue::Consumer::empty()
*** SIGSEGV (@0x8) received by PID 1297 (TID 0x2b87173b0700) from PID 8; stack
trace: ***
@ 0x2b870d9fc330 (unknown)
@ 0x2b870b2ea068 process::EventQueue::Consumer::empty()
I0804 12:34:00.866907 1305 state.cpp:64] Recovering state from
'/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_t4cOuD/meta'
I0804 12:34:00.867260 1302 status_update_manager.cpp:203] Recovering status
update manager
I0804 12:34:00.867509 1303 containerizer.cpp:598] Recovering containerizer
@ 0x2b870b2d2730 process::ProcessManager::resume()
@ 0x2b870b2cea60
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b870b2e1038
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
I0804 12:34:00.869567 1299 provisioner.cpp:416] Provisioner recovery complete
I0804 12:34:00.869966 1299 slave.cpp:6207] Finished recovery
@ 0x2b870b2e0f8f
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b870b2e0f28
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
I0804 12:34:00.870568 1299 slave.cpp:6389] Querying resource estimator for
oversubscribable resources
I0804 12:34:00.870789 1299 slave.cpp:971] New master detected at
[email protected]:40487
I0804 12:34:00.870815 1300 status_update_manager.cpp:177] Pausing sending
status updates
I0804 12:34:00.870896 1299 slave.cpp:1006] Detecting new master
I0804 12:34:00.871143 1303 slave.cpp:6403] Received oversubscribable resources
{} from the resource estimator
@ 0x2b870dcbba60 (unknown)
@ 0x2b870d9f4184 start_thread
@ 0x2b870e527ffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1501845849-4804
Untagged: mesos-1501845849-4804:latest
Deleted: sha256:ee49f1c05ac0a2d4cb7b51e3ed8b34a1702268363e35b813e75123ffcb4fee00
Deleted: sha256:658596ef24200270142b919f95e6dfe0d996a6c1a1e1475e8132f5f079424ec8
Deleted: sha256:ee030c76aa7b42946beb6d45fbb5914578e6f7aa2efb4624b484db8a2bcdfc87
Deleted: sha256:e3ceb6688b81355ec145205c9ed3c963aa3c98836b3e489407b05b0a3de47422
Deleted: sha256:9a574f76798b71369338e6501c6f2c5bfce98fe422c05b6a880f2f5f9d21a278
Deleted: sha256:87d988eb83730b237bf1f508833d915516de8cb59dd4da119a27236c4a0ab7db
Deleted: sha256:6bc70d2f215d01b4e14b473c7a6261d14ffabaedea0a41a6fa869a02a3f58cd8
Deleted: sha256:a896bce1411c118cb18e9ea62e353dbcd76a3dc0c4d3685a21ea82605506f84d
Deleted: sha256:fb94117687481c19a9510d7b8ea24baf64f2b98bdbb521f6359f6294870b7a81
Deleted: sha256:da3ff7f8439dd09202a223fc88a839b250c842895933a2de474eb6e7e167ca35
Deleted: sha256:de5ab7848f5ad71e349dff87e415a57388e006ec7bf6e6d8b92baaaa95df429a
Deleted: sha256:a5f59b95ec3499f2addd43fb41d9dde6e5e83365799271a8388b6b1501706758
Deleted: sha256:e884652a5510c2b4b525d63f84311a42361c3b21751c116d8593f371b62cd3c9
Deleted: sha256:6582ff8d08b7d2c0a724773672db5e02636bb026b2ca5a6c7407ab3efbb4f459
Deleted: sha256:bd6cf960083dcdaabc1e3b403df129bae95bf168f2590f02505118174e2136c9
Deleted: sha256:803ebb56afbbbf38ea68932a036fe33dcb5c4c9d928ec19f7c913262af57c882
Deleted: sha256:a5448ce71aa0c474d73bdbdc7729246b58c02949134caf0d6db23e14aaa81b69
Deleted: sha256:643a1bd6639d30ba7203f0821003b9295fe1ffad979c616ffd793e92c1f0c684
Deleted: sha256:724b68dd117641a3fe420cdc08d32f84b759157eb69cb98613d7cb891d734f91
Deleted: sha256:45f39c59ab42f74e7e15b2403b4994fd9b7856e4d65f1992bca3b69694d968c9
Deleted: sha256:9d6372ba86578277536169cdee3a595fe49725d2f76e850c4a020e913fdae13c
Deleted: sha256:83a7f88d5e8c16970654644550fa9a1bd56905d4bc96d3a6f198532851d33d88
Deleted: sha256:7925d7395da74cccf25ea7ee3c92a07c057a3fce51fcde6ec9cfa5a82816fa3d
Deleted: sha256:eb46b29b894dc85ea6471e251fb2f933c17c95151708e4aae632ee16776322ae
Build step 'Execute shell' marked build as failure
