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) #4643
Date Thu, 21 Dec 2017 08:06:00 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)/4643/display/redirect>

------------------------------------------
[...truncated 30.13 MB...]
3: I1221 08:05:54.606839 18414 containerizer.cpp:1172] Trying to chown '/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ/slaves/cb476f42-d475-4017-ad1b-2bc20c134438-S0/frameworks/cb476f42-d475-4017-ad1b-2bc20c134438-0000/executors/E/runs/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/f7e136c5-2f46-4c1c-84ba-4f67b507a91d'
to user 'mesos'
3: I1221 08:05:54.606976 18414 containerizer.cpp:1207] Starting container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
3: I1221 08:05:54.607205 18416 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.607512 18414 containerizer.cpp:1380] Checkpointed ContainerConfig at '/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_zdOUQk/containers/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/f7e136c5-2f46-4c1c-84ba-4f67b507a91d/config'
3: I1221 08:05:54.607543 18414 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d from PROVISIONING
to PREPARING
3: I1221 08:05:54.608026 18422 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.608435 18422 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.609230 18413 process.cpp:3503] Handling HTTP event for process 'master'
with path: '/master/api/v1/scheduler'
3: I1221 08:05:54.609170 18425 containerizer.cpp:1856] Launching 'mesos-containerizer' with
flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ\/slaves\/cb476f42-d475-4017-ad1b-2bc20c134438-S0\/frameworks\/cb476f42-d475-4017-ad1b-2bc20c134438-0000\/executors\/E\/runs\/b6732432-328b-4964-8d4d-48cdee0d2faf\/containers\/832be8bf-abb3-4575-b6d5-905a562276ef"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"172.17.0.4"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ\/slaves\/cb476f42-d475-4017-ad1b-2bc20c134438-S0\/frameworks\/cb476f42-d475-4017-ad1b-2bc20c134438-0000\/executors\/E\/runs\/b6732432-328b-4964-8d4d-48cdee0d2faf\/containers\/832be8bf-abb3-4575-b6d5-905a562276ef"}"
--pipe_read="23" --pipe_write="24" --runtime_directory="/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_zdOUQk/containers/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/832be8bf-abb3-4575-b6d5-905a562276ef"
--unshare_namespace_mnt="false"'
3: I1221 08:05:54.611721 18425 launcher.cpp:140] Forked child with pid '27535' for container
'b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef'
3: I1221 08:05:54.612288 18425 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef from PREPARING to
ISOLATING
3: I1221 08:05:54.613407 18425 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef from ISOLATING to
FETCHING
3: I1221 08:05:54.613627 18429 fetcher.cpp:369] Starting to fetch URIs for container: b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef,
directory: /tmp/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ/slaves/cb476f42-d475-4017-ad1b-2bc20c134438-S0/frameworks/cb476f42-d475-4017-ad1b-2bc20c134438-0000/executors/E/runs/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/832be8bf-abb3-4575-b6d5-905a562276ef
3: I1221 08:05:54.614316 18418 containerizer.cpp:1856] Launching 'mesos-containerizer' with
flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ\/slaves\/cb476f42-d475-4017-ad1b-2bc20c134438-S0\/frameworks\/cb476f42-d475-4017-ad1b-2bc20c134438-0000\/executors\/E\/runs\/b6732432-328b-4964-8d4d-48cdee0d2faf\/containers\/f7e136c5-2f46-4c1c-84ba-4f67b507a91d"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"172.17.0.4"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ\/slaves\/cb476f42-d475-4017-ad1b-2bc20c134438-S0\/frameworks\/cb476f42-d475-4017-ad1b-2bc20c134438-0000\/executors\/E\/runs\/b6732432-328b-4964-8d4d-48cdee0d2faf\/containers\/f7e136c5-2f46-4c1c-84ba-4f67b507a91d"}"
--pipe_read="27" --pipe_write="28" --runtime_directory="/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_zdOUQk/containers/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/f7e136c5-2f46-4c1c-84ba-4f67b507a91d"
--unshare_namespace_mnt="false"'
3: I1221 08:05:54.616046 18418 launcher.cpp:140] Forked child with pid '27536' for container
'b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d'
3: I1221 08:05:54.616549 18418 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d from PREPARING to
ISOLATING
3: I1221 08:05:54.617426 18418 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef from FETCHING to
RUNNING
3: I1221 08:05:54.618109 18418 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d from ISOLATING to
FETCHING
3: I1221 08:05:54.618289 18413 fetcher.cpp:369] Starting to fetch URIs for container: b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d,
directory: /tmp/ContentType_SchedulerTest_TaskGroupRunning_1_F0SoVZ/slaves/cb476f42-d475-4017-ad1b-2bc20c134438-S0/frameworks/cb476f42-d475-4017-ad1b-2bc20c134438-0000/executors/E/runs/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/f7e136c5-2f46-4c1c-84ba-4f67b507a91d
3: I1221 08:05:54.619401 18417 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d from FETCHING to
RUNNING
3: I1221 08:05:54.622341 27529 default_executor.cpp:641] Successfully launched tasks [ 1,
2 ] in child containers [ b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef,
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d ]
3: I1221 08:05:54.623787 27529 default_executor.cpp:714] Waiting for child container b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
of task '1'
3: I1221 08:05:54.624017 27529 default_executor.cpp:714] Waiting for child container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
of task '2'
3: I1221 08:05:54.624538 18415 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1/executor'
3: I1221 08:05:54.625181 18424 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1'
3: I1221 08:05:54.625721 18412 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1'
3: I1221 08:05:54.628173 18413 http.cpp:1185] HTTP POST for /slave(833)/api/v1 from 172.17.0.4:54758
3: I1221 08:05:54.628636 18413 http.cpp:1185] HTTP POST for /slave(833)/api/v1 from 172.17.0.4:54760
3: I1221 08:05:54.629173 18413 http.cpp:2742] Processing WAIT_NESTED_CONTAINER call for container
'b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef'
3: I1221 08:05:54.629685 18413 http.cpp:2742] Processing WAIT_NESTED_CONTAINER call for container
'b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d'
3: I1221 08:05:54.648335 18425 process.cpp:3503] Handling HTTP event for process 'master'
with path: '/master/api/v1/scheduler'
3: I1221 08:05:54.649168 18425 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from
172.17.0.4:54748
3: I1221 08:05:54.649624 18425 master.cpp:5843] Processing ACKNOWLEDGE call 020cd5a1-dac6-458b-9953-2baaf0fc70a7
for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 (default) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
3: I1221 08:05:54.650015 18427 task_status_update_manager.cpp:401] Received task status update
acknowledgement (UUID: 020cd5a1-dac6-458b-9953-2baaf0fc70a7) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.650315 18414 slave.cpp:4018] Task status update manager successfully handled
status update acknowledgement (UUID: 020cd5a1-dac6-458b-9953-2baaf0fc70a7) for task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.650352 18422 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from
172.17.0.4:54748
3: I1221 08:05:54.650671 18422 master.cpp:5843] Processing ACKNOWLEDGE call e9e8c01d-56f8-4d62-9e5a-6c417cac8778
for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 (default) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
3: I1221 08:05:54.650969 18432 task_status_update_manager.cpp:401] Received task status update
acknowledgement (UUID: e9e8c01d-56f8-4d62-9e5a-6c417cac8778) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.651187 18417 slave.cpp:4018] Task status update manager successfully handled
status update acknowledgement (UUID: e9e8c01d-56f8-4d62-9e5a-6c417cac8778) for task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.659827 18419 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1/executor'
3: I1221 08:05:54.662698 18422 http.cpp:1185] HTTP POST for /slave(833)/api/v1/executor from
172.17.0.4:54752
3: I1221 08:05:54.662957 18422 slave.cpp:4770] Handling status update TASK_RUNNING (Status
UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.663398 18422 http.cpp:1185] HTTP POST for /slave(833)/api/v1/executor from
172.17.0.4:54752
3: I1221 08:05:54.663595 18422 slave.cpp:4770] Handling status update TASK_RUNNING (Status
UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.664394 18425 task_status_update_manager.cpp:328] Received task status update
TASK_RUNNING (Status UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.664543 18425 task_status_update_manager.cpp:383] Forwarding task status
update TASK_RUNNING (Status UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 to the agent
3: I1221 08:05:54.664708 18412 slave.cpp:5253] Forwarding the update TASK_RUNNING (Status
UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
to master@172.17.0.4:45987
3: I1221 08:05:54.664808 18425 task_status_update_manager.cpp:328] Received task status update
TASK_RUNNING (Status UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.664913 18425 task_status_update_manager.cpp:383] Forwarding task status
update TASK_RUNNING (Status UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 to the agent
3: I1221 08:05:54.664950 18412 slave.cpp:5146] Task status update manager successfully handled
status update TASK_RUNNING (Status UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1
of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.665161 18427 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 933385f6-e271-4d34-a385-700a3abb1e74)
for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 from agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.665206 18427 master.cpp:7906] Forwarding status update TASK_RUNNING (Status
UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.665205 18412 slave.cpp:5253] Forwarding the update TASK_RUNNING (Status
UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
to master@172.17.0.4:45987
3: I1221 08:05:54.665371 18412 slave.cpp:5146] Task status update manager successfully handled
status update TASK_RUNNING (Status UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2
of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.665908 18427 master.cpp:10153] Updating the state of task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_RUNNING, status update state:
TASK_RUNNING)
3: I1221 08:05:54.666146 18427 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5)
for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 from agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.666184 18427 master.cpp:7906] Forwarding status update TASK_RUNNING (Status
UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.666445 27531 default_executor.cpp:191] Received ACKNOWLEDGED event
3: I1221 08:05:54.666726 27513 default_executor.cpp:191] Received ACKNOWLEDGED event
3: I1221 08:05:54.666838 18427 master.cpp:10153] Updating the state of task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_RUNNING, status update state:
TASK_RUNNING)
3: I1221 08:05:54.667492 18417 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.668542 18423 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.669428 18433 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.669854 18433 scheduler.cpp:247] Sending ACKNOWLEDGE call to http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.670795 18417 process.cpp:3503] Handling HTTP event for process 'master'
with path: '/master/api/v1/scheduler'
3: I1221 08:05:54.709403 18421 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from
172.17.0.4:54748
3: I1221 08:05:54.709952 18421 master.cpp:5843] Processing ACKNOWLEDGE call 933385f6-e271-4d34-a385-700a3abb1e74
for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 (default) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
3: I1221 08:05:54.710363 18421 process.cpp:3503] Handling HTTP event for process 'master'
with path: '/master/api/v1/scheduler'
3: I1221 08:05:54.711199 18421 task_status_update_manager.cpp:401] Received task status update
acknowledgement (UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.711547 18412 slave.cpp:4018] Task status update manager successfully handled
status update acknowledgement (UUID: 933385f6-e271-4d34-a385-700a3abb1e74) for task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.712608 18414 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from
172.17.0.4:54748
3: I1221 08:05:54.713078 18414 master.cpp:5843] Processing ACKNOWLEDGE call 7470c620-d30c-4f9e-a53b-f9724f9e84b5
for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 (default) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
3: I1221 08:05:54.713455 18432 task_status_update_manager.cpp:401] Received task status update
acknowledgement (UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.713735 18417 slave.cpp:4018] Task status update manager successfully handled
status update acknowledgement (UUID: 7470c620-d30c-4f9e-a53b-f9724f9e84b5) for task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.800032 18424 containerizer.cpp:2805] Container b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
has exited
3: I1221 08:05:54.800076 18424 containerizer.cpp:2352] Destroying container b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
in RUNNING state
3: I1221 08:05:54.800096 18424 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef from RUNNING to
DESTROYING
3: I1221 08:05:54.800557 18424 containerizer.cpp:2805] Container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
has exited
3: I1221 08:05:54.800585 18424 containerizer.cpp:2352] Destroying container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
in RUNNING state
3: I1221 08:05:54.800608 18424 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d from RUNNING to
DESTROYING
3: I1221 08:05:54.800900 18424 launcher.cpp:156] Asked to destroy container b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
3: I1221 08:05:54.802795 18424 launcher.cpp:156] Asked to destroy container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
3: I1221 08:05:54.806404 18416 provisioner.cpp:598] Ignoring destroy request for unknown container
b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
3: I1221 08:05:54.806612 18429 containerizer.cpp:2642] Checkpointing termination state to
nested container's runtime directory '/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_zdOUQk/containers/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/832be8bf-abb3-4575-b6d5-905a562276ef/termination'
3: I1221 08:05:54.806731 18416 provisioner.cpp:598] Ignoring destroy request for unknown container
b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
3: I1221 08:05:54.815618 18429 containerizer.cpp:2642] Checkpointing termination state to
nested container's runtime directory '/tmp/ContentType_SchedulerTest_TaskGroupRunning_1_zdOUQk/containers/b6732432-328b-4964-8d4d-48cdee0d2faf/containers/f7e136c5-2f46-4c1c-84ba-4f67b507a91d/termination'
3: I1221 08:05:54.817404 18411 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1/executor'
3: I1221 08:05:54.811003 27515 default_executor.cpp:889] Child container b6732432-328b-4964-8d4d-48cdee0d2faf.832be8bf-abb3-4575-b6d5-905a562276ef
of task '1' completed in state TASK_FINISHED: Command exited with status 0
3: I1221 08:05:54.827745 27515 default_executor.cpp:889] Child container b6732432-328b-4964-8d4d-48cdee0d2faf.f7e136c5-2f46-4c1c-84ba-4f67b507a91d
of task '2' completed in state TASK_FINISHED: Command exited with status 0
3: I1221 08:05:54.827811 27515 default_executor.cpp:1018] Terminating after 1secs
3: I1221 08:05:54.852573 18428 process.cpp:3503] Handling HTTP event for process 'slave(833)'
with path: '/slave(833)/api/v1/executor'
3: I1221 08:05:54.855937 18420 http.cpp:1185] HTTP POST for /slave(833)/api/v1/executor from
172.17.0.4:54752
3: I1221 08:05:54.856346 18420 slave.cpp:4770] Handling status update TASK_FINISHED (Status
UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.856909 18420 http.cpp:1185] HTTP POST for /slave(833)/api/v1/executor from
172.17.0.4:54752
3: I1221 08:05:54.857126 18420 slave.cpp:4770] Handling status update TASK_FINISHED (Status
UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.859489 18422 task_status_update_manager.cpp:328] Received task status update
TASK_FINISHED (Status UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.859685 18422 task_status_update_manager.cpp:383] Forwarding task status
update TASK_FINISHED (Status UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 to the agent
3: I1221 08:05:54.860013 18429 task_status_update_manager.cpp:328] Received task status update
TASK_FINISHED (Status UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.860133 18429 task_status_update_manager.cpp:383] Forwarding task status
update TASK_FINISHED (Status UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 to the agent
3: I1221 08:05:54.860273 18421 slave.cpp:5253] Forwarding the update TASK_FINISHED (Status
UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
to master@172.17.0.4:45987
3: I1221 08:05:54.860538 18421 slave.cpp:5146] Task status update manager successfully handled
status update TASK_FINISHED (Status UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1
of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.860756 18415 master.cpp:7850] Status update TASK_FINISHED (Status UUID:
90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
from agent cb476f42-d475-4017-ad1b-2bc20c134438-S0 at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.860823 18415 master.cpp:7906] Forwarding status update TASK_FINISHED (Status
UUID: 90ba2e09-1eb7-4f88-bd0c-ff403a01d99c) for task 1 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.860813 18421 slave.cpp:5253] Forwarding the update TASK_FINISHED (Status
UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
to master@172.17.0.4:45987
3: I1221 08:05:54.860993 18421 slave.cpp:5146] Task status update manager successfully handled
status update TASK_FINISHED (Status UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2
of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.861515 18415 master.cpp:10153] Updating the state of task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_FINISHED, status update state:
TASK_FINISHED)
3: I1221 08:05:54.862080 18415 master.cpp:7850] Status update TASK_FINISHED (Status UUID:
99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
from agent cb476f42-d475-4017-ad1b-2bc20c134438-S0 at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.862131 18415 master.cpp:7906] Forwarding status update TASK_FINISHED (Status
UUID: 99731033-6b78-4ec0-abb2-5c73c605dcb3) for task 2 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.862660 18421 hierarchical.cpp:1192] Recovered cpus(allocated: *):0.1; mem(allocated:
*):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated:
cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
from framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.862702 18415 master.cpp:10153] Updating the state of task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_FINISHED, status update state:
TASK_FINISHED)
3: I1221 08:05:54.863055 18431 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.863857 18421 hierarchical.cpp:1192] Recovered cpus(allocated: *):0.1; mem(allocated:
*):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated:
cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
from framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.864200 18419 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.865794 18414 master.cpp:1419] Framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(default) disconnected
3: I1221 08:05:54.865942 18414 master.cpp:3328] Deactivating framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(default)
3: I1221 08:05:54.866256 18414 master.cpp:3305] Disconnecting framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(default)
3: I1221 08:05:54.866364 18417 hierarchical.cpp:405] Deactivated framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.866627 18414 master.cpp:1434] Giving framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(default) 0ns to failover
3: I1221 08:05:54.866979 18422 master.cpp:8573] Framework failover timeout, removing framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (default)
3: I1221 08:05:54.867005 18422 master.cpp:9450] Removing framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(default)
3: I1221 08:05:54.867193 18419 slave.cpp:3398] Asked to shut down framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
by master@172.17.0.4:45987
3: I1221 08:05:54.867230 18419 slave.cpp:3423] Shutting down framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.867292 18419 slave.cpp:6129] Shutting down executor 'E' of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
(via HTTP)
3: I1221 08:05:54.868470 18422 master.cpp:10153] Updating the state of task 2 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_FINISHED, status update state:
TASK_KILLED)
3: I1221 08:05:54.868564 18422 master.cpp:10259] Removing task 2 with resources cpus(allocated:
*):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0 at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.868887 18422 master.cpp:10153] Updating the state of task 1 of framework
cb476f42-d475-4017-ad1b-2bc20c134438-0000 (latest state: TASK_FINISHED, status update state:
TASK_KILLED)
3: I1221 08:05:54.868973 18422 master.cpp:10259] Removing task 1 with resources cpus(allocated:
*):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0 at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.869362 18422 master.cpp:10288] Removing executor 'E' with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}]
of framework cb476f42-d475-4017-ad1b-2bc20c134438-0000 on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.870712 18429 hierarchical.cpp:1192] Recovered cpus(allocated: *):0.1; mem(allocated:
*):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated:
{}) on agent cb476f42-d475-4017-ad1b-2bc20c134438-S0 from framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.871131 18429 hierarchical.cpp:344] Removed framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
3: I1221 08:05:54.871446 18410 slave.cpp:931] Agent terminating
3: I1221 08:05:54.871570 18410 slave.cpp:3398] Asked to shut down framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
by @0.0.0.0:0
3: W1221 08:05:54.871603 18410 slave.cpp:3419] Ignoring shutdown framework cb476f42-d475-4017-ad1b-2bc20c134438-0000
because it is terminating
3: I1221 08:05:54.871824 18425 master.cpp:1305] Agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6) disconnected
3: I1221 08:05:54.871852 18425 master.cpp:3365] Disconnecting agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.871912 18425 master.cpp:3384] Deactivating agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
at slave(833)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.872028 18411 hierarchical.cpp:766] Agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
deactivated
3: I1221 08:05:54.872494 18419 containerizer.cpp:2352] Destroying container b6732432-328b-4964-8d4d-48cdee0d2faf
in RUNNING state
3: I1221 08:05:54.872515 18419 containerizer.cpp:2966] Transitioning the state of container
b6732432-328b-4964-8d4d-48cdee0d2faf from RUNNING to DESTROYING
3: I1221 08:05:54.872892 18419 launcher.cpp:156] Asked to destroy container b6732432-328b-4964-8d4d-48cdee0d2faf
3: I1221 08:05:54.901501 18422 containerizer.cpp:2805] Container b6732432-328b-4964-8d4d-48cdee0d2faf
has exited
3: I1221 08:05:54.904145 18416 provisioner.cpp:598] Ignoring destroy request for unknown container
b6732432-328b-4964-8d4d-48cdee0d2faf
3: I1221 08:05:54.926645 18410 master.cpp:1147] Master terminating
3: I1221 08:05:54.927682 18422 hierarchical.cpp:609] Removed agent cb476f42-d475-4017-ad1b-2bc20c134438-S0
3: [       OK ] ContentType/SchedulerTest.TaskGroupRunning/1 (730 ms)
3: [ RUN      ] ContentType/SchedulerTest.ReconcileTask/0
3: I1221 08:05:54.939558 18410 cluster.cpp:172] Creating default 'local' authorizer
3: I1221 08:05:54.943615 18414 master.cpp:456] Master 9b442733-c1f8-49c3-b951-0af5156e30f1
(dee7225acdc6) started on 172.17.0.4:45987
3: I1221 08:05:54.943650 18414 master.cpp:458] 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/zqPcCe/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/zqPcCe/master"
--zk_session_timeout="10secs"
3: I1221 08:05:54.943989 18414 master.cpp:507] Master only allowing authenticated frameworks
to register
3: I1221 08:05:54.943996 18414 master.cpp:513] Master only allowing authenticated agents to
register
3: I1221 08:05:54.944001 18414 master.cpp:519] Master only allowing authenticated HTTP frameworks
to register
3: I1221 08:05:54.944007 18414 credentials.hpp:37] Loading credentials for authentication
from '/tmp/zqPcCe/credentials'
3: I1221 08:05:54.944314 18414 master.cpp:563] Using default 'crammd5' authenticator
3: I1221 08:05:54.944479 18414 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1221 08:05:54.944665 18414 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1221 08:05:54.944810 18414 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1221 08:05:54.944928 18414 master.cpp:642] Authorization enabled
3: I1221 08:05:54.945256 18417 whitelist_watcher.cpp:77] No whitelist given
3: I1221 08:05:54.945305 18411 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1221 08:05:54.948514 18414 master.cpp:2210] Elected as the leading master!
3: I1221 08:05:54.948535 18414 master.cpp:1689] Recovering from registrar
3: I1221 08:05:54.948742 18425 registrar.cpp:347] Recovering registrar
3: I1221 08:05:54.949487 18425 registrar.cpp:391] Successfully fetched the registry (0B) in
712960ns
3: I1221 08:05:54.949621 18425 registrar.cpp:495] Applied 1 operations in 50302ns; attempting
to update the registry
3: I1221 08:05:54.950265 18425 registrar.cpp:552] Successfully updated the registry in 576us
3: I1221 08:05:54.950413 18425 registrar.cpp:424] Successfully recovered registrar
3: I1221 08:05:54.950829 18416 master.cpp:1803] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to re-register
3: I1221 08:05:54.950953 18416 hierarchical.cpp:213] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1221 08:05:54.957635 18410 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:45987
3: I1221 08:05:54.958139 18410 cluster.cpp:460] Creating default 'local' authorizer
3: I1221 08:05:54.961066 18413 slave.cpp:262] Mesos agent started on (834)@172.17.0.4:45987
3: I1221 08:05:54.961103 18413 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/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_SchedulerTest_ReconcileTask_0_Qp4TZX/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/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/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/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/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_SchedulerTest_ReconcileTask_0_Qp4TZX/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--jwt_secret_key="/tmp/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/jwt_secret_key" --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" --reconfiguration_policy="equal"
--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_SchedulerTest_ReconcileTask_0_Qp4TZX"
--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_SchedulerTest_ReconcileTask_0_q0H2iB"
--zk_session_timeout="10secs"
3: I1221 08:05:54.961850 18410 scheduler.cpp:188] Version: 1.5.0
3: I1221 08:05:54.961859 18413 credentials.hpp:86] Loading credential for authentication from
'/tmp/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/credential'
3: I1221 08:05:54.962054 18413 slave.cpp:295] Agent using credential for: test-principal
3: I1221 08:05:54.962072 18413 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ContentType_SchedulerTest_ReconcileTask_0_Qp4TZX/http_credentials'
3: I1221 08:05:54.962173 18426 scheduler.cpp:311] Using default 'basic' HTTP authenticatee
3: I1221 08:05:54.962451 18413 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-executor'
3: I1221 08:05:54.962613 18413 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-executor'
3: I1221 08:05:54.962724 18415 scheduler.cpp:494] New master detected at master@172.17.0.4:45987
3: I1221 08:05:54.962759 18415 scheduler.cpp:503] Waiting for 0ns before initiating a re-(connection)
attempt with the master
3: I1221 08:05:54.962980 18413 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1221 08:05:54.963088 18413 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readonly'
3: I1221 08:05:54.963294 18413 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1221 08:05:54.963397 18413 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readwrite'
3: I1221 08:05:54.965538 18413 slave.cpp:612] 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: I1221 08:05:54.965868 18413 slave.cpp:620] Agent attributes: [  ]
3: I1221 08:05:54.965881 18413 slave.cpp:629] Agent hostname: dee7225acdc6
3: I1221 08:05:54.966473 18422 scheduler.cpp:385] Connected with the master at http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.966495 18425 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1221 08:05:54.971983 18429 scheduler.cpp:247] Sending SUBSCRIBE call to http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.972430 18424 state.cpp:66] Recovering state from '/tmp/ContentType_SchedulerTest_ReconcileTask_0_q0H2iB/meta'
3: I1221 08:05:54.972944 18424 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1221 08:05:54.973650 18424 slave.cpp:6775] Finished recovery
3: I1221 08:05:54.973685 18423 process.cpp:3503] Handling HTTP event for process 'master'
with path: '/master/api/v1/scheduler'
3: I1221 08:05:54.974841 18430 slave.cpp:1055] New master detected at master@172.17.0.4:45987
3: I1221 08:05:54.974913 18422 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1221 08:05:54.975193 18430 slave.cpp:1110] Detecting new master
3: I1221 08:05:54.975728 18413 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from
172.17.0.4:54762
3: I1221 08:05:54.976034 18413 master.cpp:2610] Received subscription request for HTTP framework
'default'
3: I1221 08:05:54.976090 18413 master.cpp:2275] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1221 08:05:54.976637 18416 master.cpp:2745] Subscribing framework 'default' with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1221 08:05:54.976853 18416 master.cpp:9149] Adding framework 9b442733-c1f8-49c3-b951-0af5156e30f1-0000
(default) with roles {  } suppressed
3: I1221 08:05:54.977859 18434 hierarchical.cpp:297] Added framework 9b442733-c1f8-49c3-b951-0af5156e30f1-0000
3: I1221 08:05:54.978307 18434 hierarchical.cpp:1517] Performed allocation for 0 agents in
113831ns
3: I1221 08:05:54.978529 18426 scheduler.cpp:739] Enqueuing event SUBSCRIBED received from
http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.979130 18417 scheduler.cpp:739] Enqueuing event HEARTBEAT received from
http://172.17.0.4:45987/master/api/v1/scheduler
3: I1221 08:05:54.981596 18425 slave.cpp:1137] Authenticating with master master@172.17.0.4:45987
3: I1221 08:05:54.981652 18425 slave.cpp:1146] Using default CRAM-MD5 authenticatee
3: I1221 08:05:54.981941 18432 authenticatee.cpp:121] Creating new client SASL connection
3: I1221 08:05:54.982290 18433 master.cpp:8928] Authenticating slave(834)@172.17.0.4:45987
3: I1221 08:05:54.982465 18411 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1472)@172.17.0.4:45987
3: I1221 08:05:54.982723 18434 authenticator.cpp:98] Creating new server SASL connection
3: I1221 08:05:54.982962 18412 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1221 08:05:54.982986 18412 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1221 08:05:54.983072 18412 authenticator.cpp:204] Received SASL authentication start
3: I1221 08:05:54.983119 18412 authenticator.cpp:326] Authentication requires more steps
3: I1221 08:05:54.983222 18423 authenticatee.cpp:259] Received SASL authentication step
3: I1221 08:05:54.983345 18424 authenticator.cpp:232] Received SASL authentication step
3: I1221 08:05:54.983376 18424 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'dee7225acdc6' server FQDN: 'dee7225acdc6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1221 08:05:54.983392 18424 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1221 08:05:54.983445 18424 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1221 08:05:54.983471 18424 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'dee7225acdc6' server FQDN: 'dee7225acdc6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1221 08:05:54.983484 18424 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1221 08:05:54.983491 18424 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1221 08:05:54.983510 18424 authenticator.cpp:318] Authentication success
3: I1221 08:05:54.983587 18429 authenticatee.cpp:299] Authentication success
3: I1221 08:05:54.983688 18419 master.cpp:8958] Successfully authenticated principal 'test-principal'
at slave(834)@172.17.0.4:45987
3: I1221 08:05:54.983733 18422 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1472)@172.17.0.4:45987
3: I1221 08:05:54.983846 18426 slave.cpp:1229] Successfully authenticated with master master@172.17.0.4:45987
3: I1221 08:05:54.984055 18426 slave.cpp:1708] Will retry registration in 3.647702ms if necessary
3: I1221 08:05:54.984201 18428 master.cpp:6078] Received register agent message from slave(834)@172.17.0.4:45987
(dee7225acdc6)
3: I1221 08:05:54.984246 18428 master.cpp:3867] Authorizing agent with principal 'test-principal'
3: I1221 08:05:54.984566 18413 master.cpp:6148] Authorized registration of agent at slave(834)@172.17.0.4:45987
(dee7225acdc6)
3: I1221 08:05:54.984663 18413 master.cpp:6241] Registering agent at slave(834)@172.17.0.4:45987
(dee7225acdc6) with id 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
3: I1221 08:05:54.985139 18413 registrar.cpp:495] Applied 1 operations in 86288ns; attempting
to update the registry
3: I1221 08:05:54.985721 18413 registrar.cpp:552] Successfully updated the registry in 524032ns
3: I1221 08:05:54.985857 18432 master.cpp:6289] Admitted agent 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
at slave(834)@172.17.0.4:45987 (dee7225acdc6)
3: I1221 08:05:54.986433 18432 master.cpp:6338] Registered agent 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
at slave(834)@172.17.0.4:45987 (dee7225acdc6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1221 08:05:54.986507 18411 slave.cpp:1275] Registered with master master@172.17.0.4:45987;
given agent ID 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
3: I1221 08:05:54.986580 18412 task_status_update_manager.cpp:188] Resuming sending task status
updates
3: I1221 08:05:54.986770 18411 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_ReconcileTask_0_q0H2iB/meta/slaves/9b442733-c1f8-49c3-b951-0af5156e30f1-S0/slave.info'
3: I1221 08:05:54.986773 18434 hierarchical.cpp:574] Added agent 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
(dee7225acdc6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1221 08:05:54.987123 18411 slave.cpp:1352] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"A3TYeVmxScSVAWbHcQef8Q=="},"slave_id":{"value":"9b442733-c1f8-49c3-b951-0af5156e30f1-S0"},"update_oversubscribed_resources":true}
3: I1221 08:05:54.987362 18429 master.cpp:7268] Received update of agent 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
at slave(834)@172.17.0.4:45987 (dee7225acdc6) with total oversubscribed resources {}
3: I1221 08:05:54.987624 18429 master.cpp:7362] Ignoring update on agent 9b442733-c1f8-49c3-b951-0af5156e30f1-S0
at slave(834)@172.17.0.4:45987 (dee7225acdc6) as it reports no changes
write /dev/stdout: resource temporarily unavailable
Build step 'Execute shell' marked build as failure


Mime
View raw message