[
https://issues.apache.org/jira/browse/MESOS-6004?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Michael Thomas updated MESOS-6004:
----------------------------------
Description:
When scheduling more than one task on the same agent, all tasks fail a as
containers seem to be destroyed during provisioning.
Specifically, the errors on the agent logs are:
{noformat}
E0808 15:53:09.691315 30996 slave.cpp:3976] Container
'eb20f642-bb90-4293-8eec-6f1576ccaeb1' for executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start: Container is being
destroyed during provisioning
{noformat}
and
{noformat}
I0808 15:52:32.510210 30999 slave.cpp:4539] Terminating executor ''2' of
framework c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not
register within 3mins
{noformat}
As the default provisioning method {{copy}} is being used, I assume this is due
to the provisioning of multiple containers taking too long and the agent will
not wait. For large images, this method is simply not performant.
The issue did not occur, when only one tasks was scheduled.
Increasing the {{executor_registration_timeout}} parameter, seemed to help a
bit as it allowed to schedule at least 2 tasks at the same time. But still
fails with more (5 in this case)
h4. Complete logs
(with GLOG_v=1)
{noformat}
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.800375 3738
slave.cpp:198] Agent started on 1)@172.31.23.17:5051
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.800403 3738
slave.cpp:199] Flags at startup: --appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="false"
--authenticate_http_readwrite="false" --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,docker" --default_role="*"
--disk_watch_interval="1mins" --docker="docker"
--docker_config="{"auths":{"https:\/\/index.docker.io\/v1\/":{"auth":"dGVycmFsb3VwZTpUYWxFWUFOSXR5","email":"[email protected]"}}}"
--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="/mnt/t" --docker_volume_checkp
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]:
oint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/mesos/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname="ec2-52-59-113-0.eu-central-1.compute.amazonaws.com"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false" --image_providers="docker"
--image_provisioner_backend="copy" --initialize_driver_logging="true"
--isolation="filesystem/linux,docker/runtime,cgroups/devices,gpu/nvidia"
--launcher_dir="/usr/libexec/mesos" --log_dir="/var/log/mesos" --logbufsecs="0"
--logging_level="INFO" --master="zk://172.31.19.240:2181/mesos"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --re
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: covery_timeout="15mins"
--registration_backoff_factor="1secs" --revocable_cpu_low_priority="true"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="false"
--systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/mnt/mesos"
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: 2016-08-09
10:11:41,800:3738(0x7f71172a1700):ZOO_INFO@check_events@1728: initiated
connection to server [172.31.19.240:2181]
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801007 3738
slave.cpp:519] Agent resources: gpus(*):1; cpus(*):8; mem(*):14014;
disk(*):60257; ports(*):[31000-32000]
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801059 3738
slave.cpp:527] Agent attributes: [ ]
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801077 3738
slave.cpp:532] Agent hostname:
ec2-52-59-113-0.eu-central-1.compute.amazonaws.com
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.802232 3772
state.cpp:57] Recovering state from '/mnt/mesos/meta'
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803539 3774
status_update_manager.cpp:200] Recovering status update manager
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803745 3772
containerizer.cpp:522] Recovering containerizer
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803788 3770
docker.cpp:775] Recovering Docker containers
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.808550 3777
metadata_manager.cpp:251] Successfully loaded 2 Docker images
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.808688 3771
provisioner.cpp:253] Provisioner recovery complete
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: 2016-08-09
10:11:41,816:3738(0x7f71172a1700):ZOO_INFO@check_events@1775: session
establishment complete on server [172.31.19.240:2181],
sessionId=0x1566a66ab9b0010, negotiated timeout=10000
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817003 3774
group.cpp:349] Group process (group(1)@172.31.23.17:5051) connected to ZooKeeper
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817116 3774
group.cpp:837] Syncing group operations: queue size (joins, cancels, datas) =
(0, 0, 0)
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817147 3774
group.cpp:427] Trying to create path '/mesos' in ZooKeeper
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.818857 3774
detector.cpp:152] Detected a new leader: (id='64')
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.819005 3776
group.cpp:706] Trying to get '/mesos/json.info_0000000064' in ZooKeeper
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.819855 3775
zookeeper.cpp:259] A new leading master ([email protected]:5050) is
detected
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.899876 3777
slave.cpp:4782] Finished recovery
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900573 3777
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900687 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
6.99998957623704days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900729 3777
slave.cpp:4815] Garbage collecting old agent
c9852a23-bc07-422d-8d69-23c167a1924d-S0
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900779 3773
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
6.99998957520889days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900817 3777
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900825 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
6.99998957451259days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900899 3773
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
6.99998957412148days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900933 3777
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900944 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
6.99998957332148days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901010 3773
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
6.99998957291556days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901051 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
6.99998957196741days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901101 3773
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
6.99998957156148days in the future
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901201 3775
status_update_manager.cpp:174] Pausing sending status updates
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901203 3777
slave.cpp:895] New master detected at [email protected]:5050
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901278 3777
slave.cpp:916] No credentials provided. Attempting to register without
authentication
Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901317 3777
slave.cpp:927] Detecting new master
Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267201 3774
slave.cpp:1197] Re-registered with master [email protected]:5050
Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267302 3771
status_update_manager.cpp:181] Resuming sending status updates
Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267302 3774
slave.cpp:1233] Forwarding total oversubscribed resources
Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267577 3774
slave.cpp:2526] Updated checkpointed resources from to
Aug 9 10:11:48 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:48.269104 3777
slave.cpp:2218] Asked to shut down framework
c9852a23-bc07-422d-8d69-23c167a1924d-0002 by [email protected]:5050
Aug 9 10:11:48 ip-172-31-23-17 mesos-slave[3738]: W0809 10:11:48.269186 3777
slave.cpp:2233] Cannot shut down unknown framework
c9852a23-bc07-422d-8d69-23c167a1924d-0002
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.969521 3772
slave.cpp:1495] Got assigned task 0 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.970026 3772
slave.cpp:1614] Launching task 0 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.970826 3772
slave.cpp:5674] Launching executor 0 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0/runs/09a77a80-f904-4581-966a-734d24ae96f3'
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.971051 3772
slave.cpp:1840] Queuing task '0' for executor '0' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.971212 3773
containerizer.cpp:781] Starting container
'09a77a80-f904-4581-966a-734d24ae96f3' for executor '0' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.973953 3774
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/09a77a80-f904-4581-966a-734d24ae96f3/backends/copy/rootfses/ec9bb10e-6726-4872-be2b-4a72dfc0fafc'
for container 09a77a80-f904-4581-966a-734d24ae96f3
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978344 3774
slave.cpp:1495] Got assigned task 1 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978687 3774
slave.cpp:1614] Launching task 1 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978976 3774
slave.cpp:5674] Launching executor 1 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1/runs/0cea1dc7-b523-4b68-851a-531ed101de5a'
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.979173 3774
slave.cpp:1840] Queuing task '1' for executor '1' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.979387 3777
containerizer.cpp:781] Starting container
'0cea1dc7-b523-4b68-851a-531ed101de5a' for executor '1' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.981374 3776
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/0cea1dc7-b523-4b68-851a-531ed101de5a/backends/copy/rootfses/e3af89fe-c889-45fc-929b-f008b18e5d4e'
for container 0cea1dc7-b523-4b68-851a-531ed101de5a
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.983610 3776
slave.cpp:1495] Got assigned task 2 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.983947 3776
slave.cpp:1614] Launching task 2 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984236 3776
slave.cpp:5674] Launching executor 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2/runs/a4c972da-b12d-4b4b-8481-79ca320ddab7'
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984450 3776
slave.cpp:1840] Queuing task '2' for executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984778 3777
containerizer.cpp:781] Starting container
'a4c972da-b12d-4b4b-8481-79ca320ddab7' for executor '2' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.986330 3776
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/a4c972da-b12d-4b4b-8481-79ca320ddab7/backends/copy/rootfses/feaf70a4-df43-45c9-b6be-a654c9210a54'
for container a4c972da-b12d-4b4b-8481-79ca320ddab7
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.996104 3771
slave.cpp:1495] Got assigned task 3 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.996462 3771
slave.cpp:1614] Launching task 3 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997241 3771
slave.cpp:5674] Launching executor 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3/runs/d9363f0d-645b-468f-947a-8bf97d15a630'
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997406 3771
slave.cpp:1840] Queuing task '3' for executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997640 3777
containerizer.cpp:781] Starting container
'd9363f0d-645b-468f-947a-8bf97d15a630' for executor '3' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.999593 3777
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/d9363f0d-645b-468f-947a-8bf97d15a630/backends/copy/rootfses/eeb1b066-94b8-4880-a696-090f830cf2d6'
for container d9363f0d-645b-468f-947a-8bf97d15a630
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.003924 3775
slave.cpp:1495] Got assigned task 4 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.004278 3775
slave.cpp:1614] Launching task 4 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005383 3775
slave.cpp:5674] Launching executor 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4/runs/58c398e2-38f9-4389-87ba-8696c9a523eb'
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005681 3775
slave.cpp:1840] Queuing task '4' for executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005971 3772
containerizer.cpp:781] Starting container
'58c398e2-38f9-4389-87ba-8696c9a523eb' for executor '4' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.007786 3777
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/58c398e2-38f9-4389-87ba-8696c9a523eb/backends/copy/rootfses/78b7bf3a-6f3b-4f8a-b4fa-98a6ea4bf8f5'
for container 58c398e2-38f9-4389-87ba-8696c9a523eb
Aug 9 10:12:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:41.801560 3777
slave.cpp:4591] Current disk usage 43.28%. Max allowed age:
3.270091966898553days
Aug 9 10:12:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:54.972057 3772
slave.cpp:4539] Terminating executor ''0' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
1mins
Aug 9 10:12:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:54.972322 3775
containerizer.cpp:1622] Destroying container
'09a77a80-f904-4581-966a-734d24ae96f3'
Aug 9 10:13:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:00.979907 3777
slave.cpp:4539] Terminating executor ''1' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
1mins
Aug 9 10:13:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:00.980058 3775
containerizer.cpp:1622] Destroying container
'0cea1dc7-b523-4b68-851a-531ed101de5a'
Aug 9 10:13:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:06.985003 3777
slave.cpp:4539] Terminating executor ''2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
1mins
Aug 9 10:13:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:06.985255 3774
containerizer.cpp:1622] Destroying container
'a4c972da-b12d-4b4b-8481-79ca320ddab7'
Aug 9 10:13:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:12.998438 3777
slave.cpp:4539] Terminating executor ''3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
1mins
Aug 9 10:13:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:12.998641 3776
containerizer.cpp:1622] Destroying container
'd9363f0d-645b-468f-947a-8bf97d15a630'
Aug 9 10:13:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:19.006058 3773
slave.cpp:4539] Terminating executor ''4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
1mins
Aug 9 10:13:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:19.006228 3773
containerizer.cpp:1622] Destroying container
'58c398e2-38f9-4389-87ba-8696c9a523eb'
Aug 9 10:13:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:41.802209 3775
slave.cpp:4591] Current disk usage 57.59%. Max allowed age:
2.269006805055764days
Aug 9 10:14:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:14:41.803022 3776
slave.cpp:4591] Current disk usage 68.70%. Max allowed age:
1.491204118572176days
Aug 9 10:15:01 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:01.516207 3775
slave.cpp:3976] Container '58c398e2-38f9-4389-87ba-8696c9a523eb' for executor
'4' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to start:
Container is being destroyed during provisioning
Aug 9 10:15:01 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:01.518277 3772
composing.cpp:541] Container '58c398e2-38f9-4389-87ba-8696c9a523eb' is already
destroyed
Aug 9 10:15:02 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:02.291764 3775
slave.cpp:3976] Container '09a77a80-f904-4581-966a-734d24ae96f3' for executor
'0' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to start:
Container is being destroyed during provisioning
Aug 9 10:15:02 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:02.291877 3775
composing.cpp:541] Container '09a77a80-f904-4581-966a-734d24ae96f3' is already
destroyed
Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:03.022406 3774
slave.cpp:3976] Container 'd9363f0d-645b-468f-947a-8bf97d15a630' for executor
'3' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to start:
Container is being destroyed during provisioning
Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:03.022595 3771
composing.cpp:541] Container 'd9363f0d-645b-468f-947a-8bf97d15a630' is already
destroyed
Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:03.461519 3775
slave.cpp:3976] Container '0cea1dc7-b523-4b68-851a-531ed101de5a' for executor
'1' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to start:
Container is being destroyed during provisioning
Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:03.461643 3774
composing.cpp:541] Container '0cea1dc7-b523-4b68-851a-531ed101de5a' is already
destroyed
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.190901 3776
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/58c398e2-38f9-4389-87ba-8696c9a523eb/backends/copy/rootfses/78b7bf3a-6f3b-4f8a-b4fa-98a6ea4bf8f5'
for container 58c398e2-38f9-4389-87ba-8696c9a523eb
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:04.190904 3777
slave.cpp:3976] Container 'a4c972da-b12d-4b4b-8481-79ca320ddab7' for executor
'2' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to start:
Container is being destroyed during provisioning
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:04.191128 3770
composing.cpp:541] Container 'a4c972da-b12d-4b4b-8481-79ca320ddab7' is already
destroyed
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197221 3776
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/09a77a80-f904-4581-966a-734d24ae96f3/backends/copy/rootfses/ec9bb10e-6726-4872-be2b-4a72dfc0fafc'
for container 09a77a80-f904-4581-966a-734d24ae96f3
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197410 3776
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/d9363f0d-645b-468f-947a-8bf97d15a630/backends/copy/rootfses/eeb1b066-94b8-4880-a696-090f830cf2d6'
for container d9363f0d-645b-468f-947a-8bf97d15a630
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197510 3776
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/0cea1dc7-b523-4b68-851a-531ed101de5a/backends/copy/rootfses/e3af89fe-c889-45fc-929b-f008b18e5d4e'
for container 0cea1dc7-b523-4b68-851a-531ed101de5a
Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197619 3776
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/a4c972da-b12d-4b4b-8481-79ca320ddab7/backends/copy/rootfses/feaf70a4-df43-45c9-b6be-a654c9210a54'
for container a4c972da-b12d-4b4b-8481-79ca320ddab7
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.390928 3776
slave.cpp:4082] Executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.395370 3776
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.402472 3776
slave.cpp:4082] Executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.402556 3776
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.406177 3772
containerizer.cpp:1451] Ignoring update for unknown container:
d9363f0d-645b-468f-947a-8bf97d15a630
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.407325 3777
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.407598 3773
containerizer.cpp:1451] Ignoring update for unknown container:
a4c972da-b12d-4b4b-8481-79ca320ddab7
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.409890 3774
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.409893 3777
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.414727 3774
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.418180 3777
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419615 3777
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419636 3774
slave.cpp:4193] Cleaning up executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419924 3772
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3/runs/d9363f0d-645b-468f-947a-8bf97d15a630'
for gc 6.9999951410637days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420703 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3'
for gc 6.99999513154074days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420722 3774
slave.cpp:4193] Cleaning up executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420897 3774
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2/runs/a4c972da-b12d-4b4b-8481-79ca320ddab7'
for gc 6.99999512931852days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420940 3774
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2'
for gc 6.99999512869333days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490155 3772
slave.cpp:4082] Executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490289 3772
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490414 3772
slave.cpp:4082] Executor '0' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490489 3772
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.490687 3773
containerizer.cpp:1451] Ignoring update for unknown container:
58c398e2-38f9-4389-87ba-8696c9a523eb
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.490774 3773
containerizer.cpp:1451] Ignoring update for unknown container:
09a77a80-f904-4581-966a-734d24ae96f3
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490867 3773
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491082 3773
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491082 3772
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491303 3774
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494438 3775
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494572 3775
slave.cpp:4193] Cleaning up executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494757 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4/runs/58c398e2-38f9-4389-87ba-8696c9a523eb'
for gc 6.99999427436444days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494817 3775
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494937 3775
slave.cpp:4193] Cleaning up executor '0' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494823 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4'
for gc 6.99999427361185days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.495107 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0/runs/09a77a80-f904-4581-966a-734d24ae96f3'
for gc 6.99999427055407days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.495149 3773
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0'
for gc 6.99999427024296days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591338 3775
slave.cpp:4082] Executor '1' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591469 3775
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.591841 3776
containerizer.cpp:1451] Ignoring update for unknown container:
0cea1dc7-b523-4b68-851a-531ed101de5a
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591974 3770
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.592157 3772
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594518 3771
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594636 3771
slave.cpp:4193] Cleaning up executor '1' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594792 3770
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1/runs/0cea1dc7-b523-4b68-851a-531ed101de5a'
for gc 6.99999311635556days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594820 3771
slave.cpp:4281] Cleaning up framework c9852a23-bc07-422d-8d69-23c167a1924d-0003
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594861 3770
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1'
for gc 6.99999311568296days in the future
Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594897 3770
status_update_manager.cpp:282] Closing status update streams for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0003
{noformat}
was:
When scheduling more than one task on the same agent, all tasks fail a as
containers seem to be destroyed during provisioning.
Specifically, the errors on the agent logs are:
{noformat}
E0808 15:53:09.691315 30996 slave.cpp:3976] Container
'eb20f642-bb90-4293-8eec-6f1576ccaeb1' for executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start: Container is being
destroyed during provisioning
{noformat}
and
{noformat}
I0808 15:52:32.510210 30999 slave.cpp:4539] Terminating executor ''2' of
framework c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not
register within 3mins
{noformat}
As the default provisioning method {{copy}} is being used, I assume this is due
to the provisioning of multiple containers taking too long and the agent will
not wait. For large images, this method is simply not performant.
The issue did not occur, when only one tasks was scheduled.
Increasing the {{executor_registration_timeout}} parameter, seemed to help a
bit as it allowed to schedule at least 2 tasks at the same time. But still
fails with more (5 in this case)
h4. Complete logs
(with GLOG_v=0, as with 1 it was too long)
{noformat}
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.661067 30961
main.cpp:434] Starting Mesos agent
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.661551 30961
slave.cpp:198] Agent started on 1)@172.31.23.17:5051
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.661578 30961
slave.cpp:199] Flags at startup: --appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="false"
--authenticate_http_readwrite="false" --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,docker" --default_role="*"
--disk_watch_interval="1mins" --docker="docker" --docker_config="XXX"
--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="/mnt/mesos/store/docker" --do
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]:
cker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="3mins"
--executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/mesos/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname="ec2-52-59-113-0.eu-central-1.compute.amazonaws.com"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false" --image_providers="docker"
--image_provisioner_backend="copy" --initialize_driver_logging="true"
--isolation="filesystem/linux,docker/runtime,cgroups/devices,gpu/nvidia"
--launcher_dir="/usr/libexec/mesos" --log_dir="/var/log/mesos" --logbufsecs="0"
--logging_level="INFO" --master="zk://172.31.19.240:2181/mesos"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recov
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: er="reconnect"
--recovery_timeout="15mins" --registration_backoff_factor="1secs"
--revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox"
--strict="true" --switch_user="false" --systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/mnt/mesos"
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.662147 30961
slave.cpp:519] Agent resources: gpus(*):1; cpus(*):8; mem(*):14014;
disk(*):60257; ports(*):[31000-32000]
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.662211 30961
slave.cpp:527] Agent attributes: [ ]
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.662230 30961
slave.cpp:532] Agent hostname:
ec2-52-59-113-0.eu-central-1.compute.amazonaws.com
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.663354 31000
state.cpp:57] Recovering state from '/mnt/mesos/meta'
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.663918 30995
status_update_manager.cpp:200] Recovering status update manager
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.664131 30996
containerizer.cpp:522] Recovering containerizer
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.664136 31000
docker.cpp:775] Recovering Docker containers
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: 2016-08-08
15:48:32,665:30961(0x7fce36077700):ZOO_INFO@check_events@1728: initiated
connection to server [172.31.19.240:2181]
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: 2016-08-08
15:48:32,667:30961(0x7fce36077700):ZOO_INFO@check_events@1775: session
establishment complete on server [172.31.19.240:2181],
sessionId=0x1566a66ab9b000a, negotiated timeout=10000
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.667783 31000
group.cpp:349] Group process (group(1)@172.31.23.17:5051) connected to ZooKeeper
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.667835 31000
group.cpp:837] Syncing group operations: queue size (joins, cancels, datas) =
(0, 0, 0)
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.667857 31000
group.cpp:427] Trying to create path '/mesos' in ZooKeeper
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.668104 30994
metadata_manager.cpp:251] Successfully loaded 1 Docker images
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.668200 30993
provisioner.cpp:253] Provisioner recovery complete
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.671417 31000
detector.cpp:152] Detected a new leader: (id='64')
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.671563 30998
group.cpp:706] Trying to get '/mesos/json.info_0000000064' in ZooKeeper
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.672611 30996
zookeeper.cpp:259] A new leading master ([email protected]:5050) is
detected
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761471 30998
slave.cpp:4782] Finished recovery
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761726 30998
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761812 30998
slave.cpp:4815] Garbage collecting old agent
c9852a23-bc07-422d-8d69-23c167a1924d-S0
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761808 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
6.99999118329778days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761893 30998
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761904 31000
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
6.99999118289481days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761978 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
6.99999118225778days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.761988 30998
slave.cpp:4815] Garbage collecting old agent
524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762029 31000
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
6.9999911819437days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762064 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
6.99999118122667days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762105 31000
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
6.9999911809037days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762168 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
6.9999911798963days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762207 31000
gc.cpp:55] Scheduling
'/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
6.99999117958222days in the future
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762230 30998
slave.cpp:895] New master detected at [email protected]:5050
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762212 30993
status_update_manager.cpp:174] Pausing sending status updates
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762277 30998
slave.cpp:916] No credentials provided. Attempting to register without
authentication
Aug 8 15:48:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:32.762344 30998
slave.cpp:927] Detecting new master
Aug 8 15:48:33 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:33.067215 30999
slave.cpp:1197] Re-registered with master [email protected]:5050
Aug 8 15:48:33 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:33.067312 30999
slave.cpp:1233] Forwarding total oversubscribed resources
Aug 8 15:48:33 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:33.067313 30993
status_update_manager.cpp:181] Resuming sending status updates
Aug 8 15:48:33 ip-172-31-23-17 mesos-slave[30961]: I0808 15:48:33.067610 31000
slave.cpp:2526] Updated checkpointed resources from to
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.507480 31000
slave.cpp:1495] Got assigned task 2 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.508069 31000
gc.cpp:83] Unscheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001'
from gc
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.508167 30998
slave.cpp:1614] Launching task 2 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.509050 30998
slave.cpp:5674] Launching executor 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/2/runs/a58a1b2c-14a4-4ada-a4b3-666d8d077597'
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.509269 30998
slave.cpp:1840] Queuing task '2' for executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.509430 30993
containerizer.cpp:781] Starting container
'a58a1b2c-14a4-4ada-a4b3-666d8d077597' for executor '2' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0001'
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.511643 30995
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/a58a1b2c-14a4-4ada-a4b3-666d8d077597/backends/copy/rootfses/a688d80b-3eb9-4301-abfc-3e6a742cc8be'
for container a58a1b2c-14a4-4ada-a4b3-666d8d077597
Aug 8 15:49:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:32.663204 30996
slave.cpp:4591] Current disk usage 17.44%. Max allowed age:
5.078955723221944days
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.511801 30995
slave.cpp:1495] Got assigned task 3 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.512176 30995
slave.cpp:1614] Launching task 3 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.512511 30995
slave.cpp:5674] Launching executor 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/3/runs/eb20f642-bb90-4293-8eec-6f1576ccaeb1'
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.512737 30995
slave.cpp:1840] Queuing task '3' for executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.512948 30998
containerizer.cpp:781] Starting container
'eb20f642-bb90-4293-8eec-6f1576ccaeb1' for executor '3' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0001'
Aug 8 15:49:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:38.514993 30996
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/eb20f642-bb90-4293-8eec-6f1576ccaeb1/backends/copy/rootfses/8d2e66c8-f0d6-4891-86e3-7e5c222adfd2'
for container eb20f642-bb90-4293-8eec-6f1576ccaeb1
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.524807 31000
slave.cpp:1495] Got assigned task 4 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.525184 31000
slave.cpp:1614] Launching task 4 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.525995 31000
slave.cpp:5674] Launching executor 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/4/runs/d248d565-e9d1-438e-8524-d71f601ba981'
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.526221 31000
slave.cpp:1840] Queuing task '4' for executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.526448 30995
containerizer.cpp:781] Starting container
'd248d565-e9d1-438e-8524-d71f601ba981' for executor '4' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0001'
Aug 8 15:49:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:44.528301 30998
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/d248d565-e9d1-438e-8524-d71f601ba981/backends/copy/rootfses/d05cefcb-6f83-49b6-a1c1-8e53d463712c'
for container d248d565-e9d1-438e-8524-d71f601ba981
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.531419 30996
slave.cpp:1495] Got assigned task 5 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.531810 30996
slave.cpp:1614] Launching task 5 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.532166 30996
slave.cpp:5674] Launching executor 5 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/5/runs/2f372338-3c40-4463-b61f-4f7e9e42766a'
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.532412 30996
slave.cpp:1840] Queuing task '5' for executor '5' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.532740 30998
containerizer.cpp:781] Starting container
'2f372338-3c40-4463-b61f-4f7e9e42766a' for executor '5' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0001'
Aug 8 15:49:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:50.534970 30993
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/2f372338-3c40-4463-b61f-4f7e9e42766a/backends/copy/rootfses/691d6235-1596-4a5d-aeef-21bf43b2cc03'
for container 2f372338-3c40-4463-b61f-4f7e9e42766a
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.540551 30999
slave.cpp:1495] Got assigned task 6 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.540967 30999
slave.cpp:1614] Launching task 6 for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.541805 30999
slave.cpp:5674] Launching executor 6 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 with resources cpus(*):0.1; mem(*):32
in work directory
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/6/runs/b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455'
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.542049 30999
slave.cpp:1840] Queuing task '6' for executor '6' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.542263 30996
containerizer.cpp:781] Starting container
'b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455' for executor '6' of framework
'c9852a23-bc07-422d-8d69-23c167a1924d-0001'
Aug 8 15:49:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:49:56.543998 30996
provisioner.cpp:294] Provisioning image rootfs
'/mnt/mesos/provisioner/containers/b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455/backends/copy/rootfses/3601630b-5b57-4a45-9e2c-cfa79eacf2fd'
for container b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455
Aug 8 15:50:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:50:32.664178 30995
slave.cpp:4591] Current disk usage 31.17%. Max allowed age:
4.117972973602755days
Aug 8 15:51:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:51:32.665262 30997
slave.cpp:4591] Current disk usage 41.95%. Max allowed age:
3.363669281005290days
Aug 8 15:52:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:32.510210 30999
slave.cpp:4539] Terminating executor ''2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not register within
3mins
Aug 8 15:52:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:32.510499 31000
containerizer.cpp:1622] Destroying container
'a58a1b2c-14a4-4ada-a4b3-666d8d077597'
Aug 8 15:52:32 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:32.665740 30994
slave.cpp:4591] Current disk usage 52.57%. Max allowed age:
2.620120712155822days
Aug 8 15:52:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:38.512807 30995
slave.cpp:4539] Terminating executor ''3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not register within
3mins
Aug 8 15:52:38 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:38.513015 30994
containerizer.cpp:1622] Destroying container
'eb20f642-bb90-4293-8eec-6f1576ccaeb1'
Aug 8 15:52:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:44.526844 30996
slave.cpp:4539] Terminating executor ''4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not register within
3mins
Aug 8 15:52:44 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:44.527053 30996
containerizer.cpp:1622] Destroying container
'd248d565-e9d1-438e-8524-d71f601ba981'
Aug 8 15:52:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:50.532806 31000
slave.cpp:4539] Terminating executor ''5' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not register within
3mins
Aug 8 15:52:50 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:50.532981 31000
containerizer.cpp:1622] Destroying container
'2f372338-3c40-4463-b61f-4f7e9e42766a'
Aug 8 15:52:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:56.542345 30995
slave.cpp:4539] Terminating executor ''6' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not register within
3mins
Aug 8 15:52:56 ip-172-31-23-17 mesos-slave[30961]: I0808 15:52:56.542580 30998
containerizer.cpp:1622] Destroying container
'b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455'
Aug 8 15:53:09 ip-172-31-23-17 mesos-slave[30961]: E0808 15:53:09.691315 30996
slave.cpp:3976] Container 'eb20f642-bb90-4293-8eec-6f1576ccaeb1' for executor
'3' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start:
Container is being destroyed during provisioning
Aug 8 15:53:09 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:09.695711 30996
composing.cpp:541] Container 'eb20f642-bb90-4293-8eec-6f1576ccaeb1' is already
destroyed
Aug 8 15:53:11 ip-172-31-23-17 mesos-slave[30961]: E0808 15:53:11.270226 30993
slave.cpp:3976] Container 'a58a1b2c-14a4-4ada-a4b3-666d8d077597' for executor
'2' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start:
Container is being destroyed during provisioning
Aug 8 15:53:11 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:11.270447 30999
composing.cpp:541] Container 'a58a1b2c-14a4-4ada-a4b3-666d8d077597' is already
destroyed
Aug 8 15:53:11 ip-172-31-23-17 mesos-slave[30961]: E0808 15:53:11.689219 30995
slave.cpp:3976] Container 'd248d565-e9d1-438e-8524-d71f601ba981' for executor
'4' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start:
Container is being destroyed during provisioning
Aug 8 15:53:11 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:11.689400 31000
composing.cpp:541] Container 'd248d565-e9d1-438e-8524-d71f601ba981' is already
destroyed
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: E0808 15:53:12.400029 30999
slave.cpp:3976] Container '2f372338-3c40-4463-b61f-4f7e9e42766a' for executor
'5' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start:
Container is being destroyed during provisioning
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:12.400218 30994
composing.cpp:541] Container '2f372338-3c40-4463-b61f-4f7e9e42766a' is already
destroyed
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:12.799772 30998
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/eb20f642-bb90-4293-8eec-6f1576ccaeb1/backends/copy/rootfses/8d2e66c8-f0d6-4891-86e3-7e5c222adfd2'
for container eb20f642-bb90-4293-8eec-6f1576ccaeb1
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: E0808 15:53:12.799855 30995
slave.cpp:3976] Container 'b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455' for executor
'6' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start:
Container is being destroyed during provisioning
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:12.810742 30999
composing.cpp:541] Container 'b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455' is already
destroyed
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:12.811067 30998
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/a58a1b2c-14a4-4ada-a4b3-666d8d077597/backends/copy/rootfses/a688d80b-3eb9-4301-abfc-3e6a742cc8be'
for container a58a1b2c-14a4-4ada-a4b3-666d8d077597
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:12.812954 30998
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/d248d565-e9d1-438e-8524-d71f601ba981/backends/copy/rootfses/d05cefcb-6f83-49b6-a1c1-8e53d463712c'
for container d248d565-e9d1-438e-8524-d71f601ba981
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:12.814383 30998
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/2f372338-3c40-4463-b61f-4f7e9e42766a/backends/copy/rootfses/691d6235-1596-4a5d-aeef-21bf43b2cc03'
for container 2f372338-3c40-4463-b61f-4f7e9e42766a
Aug 8 15:53:12 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:12.815577 30998
provisioner.cpp:434] Destroying container rootfs at
'/mnt/mesos/provisioner/containers/b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455/backends/copy/rootfses/3601630b-5b57-4a45-9e2c-cfa79eacf2fd'
for container b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.995162 31000
slave.cpp:4082] Executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 has terminated with unknown status
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.995355 31000
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
4f428b5a-1538-4f78-8383-6c9b68f67f3f) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 from @0.0.0.0:0
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:15.995841 31000
containerizer.cpp:1451] Ignoring update for unknown container:
d248d565-e9d1-438e-8524-d71f601ba981
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.996011 31000
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
4f428b5a-1538-4f78-8383-6c9b68f67f3f) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.996346 31000
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
4f428b5a-1538-4f78-8383-6c9b68f67f3f) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 to [email protected]:5050
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.998983 30993
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
4f428b5a-1538-4f78-8383-6c9b68f67f3f) for task 4 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:15 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:15.999192 30993
slave.cpp:4193] Cleaning up executor '4' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.000690 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/4/runs/d248d565-e9d1-438e-8524-d71f601ba981'
for gc 6.9999884190637days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.000754 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/4'
for gc 6.99998841775407days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.344812 30995
slave.cpp:4082] Executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 has terminated with unknown status
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.344981 30995
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
c33d9357-e749-4e05-82d2-cb305d1ff0d2) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 from @0.0.0.0:0
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:16.346043 30994
containerizer.cpp:1451] Ignoring update for unknown container:
eb20f642-bb90-4293-8eec-6f1576ccaeb1
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.346206 30998
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
c33d9357-e749-4e05-82d2-cb305d1ff0d2) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.346482 30997
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
c33d9357-e749-4e05-82d2-cb305d1ff0d2) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 to [email protected]:5050
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.348764 30998
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
c33d9357-e749-4e05-82d2-cb305d1ff0d2) for task 3 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.348863 30998
slave.cpp:4193] Cleaning up executor '3' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.348984 30998
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/3/runs/eb20f642-bb90-4293-8eec-6f1576ccaeb1'
for gc 6.99999596136593days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.349020 30998
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/3'
for gc 6.99999596097185days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.498633 30996
slave.cpp:4082] Executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 has terminated with unknown status
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.498803 30996
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
3d818e6a-f600-412c-b07d-f21794a0af30) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 from @0.0.0.0:0
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.498921 30996
slave.cpp:4082] Executor '5' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 has terminated with unknown status
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.498991 30996
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
16048628-6112-42ed-b3db-5b418a29d40d) for task 5 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 from @0.0.0.0:0
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:16.499202 30996
containerizer.cpp:1451] Ignoring update for unknown container:
a58a1b2c-14a4-4ada-a4b3-666d8d077597
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:16.499289 30996
containerizer.cpp:1451] Ignoring update for unknown container:
2f372338-3c40-4463-b61f-4f7e9e42766a
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.499400 30997
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
3d818e6a-f600-412c-b07d-f21794a0af30) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.499665 30995
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
3d818e6a-f600-412c-b07d-f21794a0af30) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 to [email protected]:5050
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.499678 30997
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
16048628-6112-42ed-b3db-5b418a29d40d) for task 5 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.499928 30997
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
16048628-6112-42ed-b3db-5b418a29d40d) for task 5 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 to [email protected]:5050
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.504577 30994
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
3d818e6a-f600-412c-b07d-f21794a0af30) for task 2 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.504712 30994
slave.cpp:4193] Cleaning up executor '2' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505421 30999
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/2/runs/a58a1b2c-14a4-4ada-a4b3-666d8d077597'
for gc 6.99999415104296days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505486 30994
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
16048628-6112-42ed-b3db-5b418a29d40d) for task 5 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505493 30999
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/2'
for gc 6.99999415021926days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505623 30994
slave.cpp:4193] Cleaning up executor '5' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505748 30993
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/5/runs/2f372338-3c40-4463-b61f-4f7e9e42766a'
for gc 6.99999414686222days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.505798 30993
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/5'
for gc 6.9999941464237days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.598873 30999
slave.cpp:4082] Executor '6' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 has terminated with unknown status
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.599030 30999
slave.cpp:3211] Handling status update TASK_FAILED (UUID:
62805972-05e7-419f-8ff3-f80a88cf8199) for task 6 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 from @0.0.0.0:0
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: W0808 15:53:16.599324 30996
containerizer.cpp:1451] Ignoring update for unknown container:
b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.599454 30996
status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
62805972-05e7-419f-8ff3-f80a88cf8199) for task 6 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.599701 30993
slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
62805972-05e7-419f-8ff3-f80a88cf8199) for task 6 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001 to [email protected]:5050
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602051 30996
status_update_manager.cpp:392] Received status update acknowledgement (UUID:
62805972-05e7-419f-8ff3-f80a88cf8199) for task 6 of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602171 30996
slave.cpp:4193] Cleaning up executor '6' of framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602313 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/6/runs/b77f9aa9-b2e5-488d-9fdd-fcbd2e5c8455'
for gc 6.99999302943407days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602334 30996
slave.cpp:4281] Cleaning up framework c9852a23-bc07-422d-8d69-23c167a1924d-0001
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602388 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001/executors/6'
for gc 6.99999302868148days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602442 31000
gc.cpp:55] Scheduling
'/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0001'
for gc 6.99999302741926days in the future
Aug 8 15:53:16 ip-172-31-23-17 mesos-slave[30961]: I0808 15:53:16.602445 30999
status_update_manager.cpp:282] Closing status update streams for framework
c9852a23-bc07-422d-8d69-23c167a1924d-0001
{noformat}
> Tasks fail when provisioning multiple containers with large docker images
> using copy backend
> ---------------------------------------------------------------------------------------------
>
> Key: MESOS-6004
> URL: https://issues.apache.org/jira/browse/MESOS-6004
> Project: Mesos
> Issue Type: Bug
> Components: containerization, docker
> Affects Versions: 0.28.2, 1.0.0
> Environment: h4. Agent Platform
> - Ubuntu 16.04
> - AWS g2.x2large instance
> - Nvidia support enabled
> h4. Agent Configuration
> -{noformat}
> --containerizers=mesos,docker
> --docker_config=<docker auth json>
> --docker_store_dir=/mnt/mesos/store/docker
> --executor_registration_timeout=3mins
> --hostname=<aws public dns>
> --image_providers=docker
> --image_provisioner_backend=copy
> --isolation=filesystem/linux,docker/runtime,cgroups/devices,gpu/nvidia
> --switch_user=false
> --work_dir=/mnt/mesos
> {noformat}
> h4. Framework
> - custom framework written in python
> - using unified containerizer with docker images
> h4. Test Setup
> * 1 master
> * 1 agent
> * 5 tasks scheduled at the same time:
> ** resources: cpus: 0.1, mem: 128
> ** command: `echo test`
> ** docker image: custom docker image, based on nvidia/cuda ~5gb
> ** the same docker image was for all tasks, already pulled.
> Reporter: Michael Thomas
> Labels: containerizer, docker, performance
>
> When scheduling more than one task on the same agent, all tasks fail a as
> containers seem to be destroyed during provisioning.
> Specifically, the errors on the agent logs are:
> {noformat}
> E0808 15:53:09.691315 30996 slave.cpp:3976] Container
> 'eb20f642-bb90-4293-8eec-6f1576ccaeb1' for executor '3' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0001 failed to start: Container is being
> destroyed during provisioning
> {noformat}
> and
> {noformat}
> I0808 15:52:32.510210 30999 slave.cpp:4539] Terminating executor ''2' of
> framework c9852a23-bc07-422d-8d69-23c167a1924d-0001' because it did not
> register within 3mins
> {noformat}
> As the default provisioning method {{copy}} is being used, I assume this is
> due to the provisioning of multiple containers taking too long and the agent
> will not wait. For large images, this method is simply not performant.
> The issue did not occur, when only one tasks was scheduled.
> Increasing the {{executor_registration_timeout}} parameter, seemed to help a
> bit as it allowed to schedule at least 2 tasks at the same time. But still
> fails with more (5 in this case)
> h4. Complete logs
> (with GLOG_v=1)
> {noformat}
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.800375
> 3738 slave.cpp:198] Agent started on 1)@172.31.23.17:5051
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.800403
> 3738 slave.cpp:199] Flags at startup:
> --appc_simple_discovery_uri_prefix="http://";
> --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="false"
> --authenticate_http_readwrite="false" --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,docker" --default_role="*"
> --disk_watch_interval="1mins" --docker="docker"
> --docker_config="{"auths":{"https:\/\/index.docker.io\/v1\/":{"auth":"dGVycmFsb3VwZTpUYWxFWUFOSXR5","email":"[email protected]"}}}"
> --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="/mnt/t" --docker_volume_checkp
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]:
> oint_dir="/var/run/mesos/isolators/docker/volume"
> --enforce_container_disk_quota="false"
> --executor_registration_timeout="1mins"
> --executor_shutdown_grace_period="5secs"
> --fetcher_cache_dir="/tmp/mesos/fetch" --fetcher_cache_size="2GB"
> --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1"
> --hadoop_home="" --help="false"
> --hostname="ec2-52-59-113-0.eu-central-1.compute.amazonaws.com"
> --hostname_lookup="true" --http_authenticators="basic"
> --http_command_executor="false" --image_providers="docker"
> --image_provisioner_backend="copy" --initialize_driver_logging="true"
> --isolation="filesystem/linux,docker/runtime,cgroups/devices,gpu/nvidia"
> --launcher_dir="/usr/libexec/mesos" --log_dir="/var/log/mesos"
> --logbufsecs="0" --logging_level="INFO"
> --master="zk://172.31.19.240:2181/mesos"
> --oversubscribed_resources_interval="15secs" --perf_duration="10secs"
> --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
> --quiet="false" --recover="reconnect" --re
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: covery_timeout="15mins"
> --registration_backoff_factor="1secs" --revocable_cpu_low_priority="true"
> --sandbox_directory="/mnt/mesos/sandbox" --strict="true"
> --switch_user="false" --systemd_enable_support="true"
> --systemd_runtime_directory="/run/systemd/system" --version="false"
> --work_dir="/mnt/mesos"
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: 2016-08-09
> 10:11:41,800:3738(0x7f71172a1700):ZOO_INFO@check_events@1728: initiated
> connection to server [172.31.19.240:2181]
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801007
> 3738 slave.cpp:519] Agent resources: gpus(*):1; cpus(*):8; mem(*):14014;
> disk(*):60257; ports(*):[31000-32000]
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801059
> 3738 slave.cpp:527] Agent attributes: [ ]
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.801077
> 3738 slave.cpp:532] Agent hostname:
> ec2-52-59-113-0.eu-central-1.compute.amazonaws.com
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.802232
> 3772 state.cpp:57] Recovering state from '/mnt/mesos/meta'
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803539
> 3774 status_update_manager.cpp:200] Recovering status update manager
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803745
> 3772 containerizer.cpp:522] Recovering containerizer
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.803788
> 3770 docker.cpp:775] Recovering Docker containers
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.808550
> 3777 metadata_manager.cpp:251] Successfully loaded 2 Docker images
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.808688
> 3771 provisioner.cpp:253] Provisioner recovery complete
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: 2016-08-09
> 10:11:41,816:3738(0x7f71172a1700):ZOO_INFO@check_events@1775: session
> establishment complete on server [172.31.19.240:2181],
> sessionId=0x1566a66ab9b0010, negotiated timeout=10000
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817003
> 3774 group.cpp:349] Group process (group(1)@172.31.23.17:5051) connected to
> ZooKeeper
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817116
> 3774 group.cpp:837] Syncing group operations: queue size (joins, cancels,
> datas) = (0, 0, 0)
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.817147
> 3774 group.cpp:427] Trying to create path '/mesos' in ZooKeeper
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.818857
> 3774 detector.cpp:152] Detected a new leader: (id='64')
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.819005
> 3776 group.cpp:706] Trying to get '/mesos/json.info_0000000064' in ZooKeeper
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.819855
> 3775 zookeeper.cpp:259] A new leading master ([email protected]:5050)
> is detected
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.899876
> 3777 slave.cpp:4782] Finished recovery
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900573
> 3777 slave.cpp:4815] Garbage collecting old agent
> 524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900687
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
> 6.99998957623704days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900729
> 3777 slave.cpp:4815] Garbage collecting old agent
> c9852a23-bc07-422d-8d69-23c167a1924d-S0
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900779
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S2' for gc
> 6.99998957520889days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900817
> 3777 slave.cpp:4815] Garbage collecting old agent
> 524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900825
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
> 6.99998957451259days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900899
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/meta/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S0' for gc
> 6.99998957412148days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900933
> 3777 slave.cpp:4815] Garbage collecting old agent
> 524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.900944
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
> 6.99998957332148days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901010
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S1' for gc
> 6.99998957291556days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901051
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
> 6.99998957196741days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901101
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/meta/slaves/524105e7-de7a-43f0-8b28-d3ff3e0c4a44-S3' for gc
> 6.99998957156148days in the future
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901201
> 3775 status_update_manager.cpp:174] Pausing sending status updates
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901203
> 3777 slave.cpp:895] New master detected at [email protected]:5050
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901278
> 3777 slave.cpp:916] No credentials provided. Attempting to register without
> authentication
> Aug 9 10:11:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:41.901317
> 3777 slave.cpp:927] Detecting new master
> Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267201
> 3774 slave.cpp:1197] Re-registered with master [email protected]:5050
> Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267302
> 3771 status_update_manager.cpp:181] Resuming sending status updates
> Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267302
> 3774 slave.cpp:1233] Forwarding total oversubscribed resources
> Aug 9 10:11:42 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:42.267577
> 3774 slave.cpp:2526] Updated checkpointed resources from to
> Aug 9 10:11:48 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:48.269104
> 3777 slave.cpp:2218] Asked to shut down framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0002 by [email protected]:5050
> Aug 9 10:11:48 ip-172-31-23-17 mesos-slave[3738]: W0809 10:11:48.269186
> 3777 slave.cpp:2233] Cannot shut down unknown framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0002
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.969521
> 3772 slave.cpp:1495] Got assigned task 0 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.970026
> 3772 slave.cpp:1614] Launching task 0 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.970826
> 3772 slave.cpp:5674] Launching executor 0 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0/runs/09a77a80-f904-4581-966a-734d24ae96f3'
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.971051
> 3772 slave.cpp:1840] Queuing task '0' for executor '0' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.971212
> 3773 containerizer.cpp:781] Starting container
> '09a77a80-f904-4581-966a-734d24ae96f3' for executor '0' of framework
> 'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
> Aug 9 10:11:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:11:54.973953
> 3774 provisioner.cpp:294] Provisioning image rootfs
> '/mnt/mesos/provisioner/containers/09a77a80-f904-4581-966a-734d24ae96f3/backends/copy/rootfses/ec9bb10e-6726-4872-be2b-4a72dfc0fafc'
> for container 09a77a80-f904-4581-966a-734d24ae96f3
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978344
> 3774 slave.cpp:1495] Got assigned task 1 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978687
> 3774 slave.cpp:1614] Launching task 1 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.978976
> 3774 slave.cpp:5674] Launching executor 1 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1/runs/0cea1dc7-b523-4b68-851a-531ed101de5a'
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.979173
> 3774 slave.cpp:1840] Queuing task '1' for executor '1' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.979387
> 3777 containerizer.cpp:781] Starting container
> '0cea1dc7-b523-4b68-851a-531ed101de5a' for executor '1' of framework
> 'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
> Aug 9 10:12:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:00.981374
> 3776 provisioner.cpp:294] Provisioning image rootfs
> '/mnt/mesos/provisioner/containers/0cea1dc7-b523-4b68-851a-531ed101de5a/backends/copy/rootfses/e3af89fe-c889-45fc-929b-f008b18e5d4e'
> for container 0cea1dc7-b523-4b68-851a-531ed101de5a
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.983610
> 3776 slave.cpp:1495] Got assigned task 2 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.983947
> 3776 slave.cpp:1614] Launching task 2 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984236
> 3776 slave.cpp:5674] Launching executor 2 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2/runs/a4c972da-b12d-4b4b-8481-79ca320ddab7'
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984450
> 3776 slave.cpp:1840] Queuing task '2' for executor '2' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.984778
> 3777 containerizer.cpp:781] Starting container
> 'a4c972da-b12d-4b4b-8481-79ca320ddab7' for executor '2' of framework
> 'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
> Aug 9 10:12:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:06.986330
> 3776 provisioner.cpp:294] Provisioning image rootfs
> '/mnt/mesos/provisioner/containers/a4c972da-b12d-4b4b-8481-79ca320ddab7/backends/copy/rootfses/feaf70a4-df43-45c9-b6be-a654c9210a54'
> for container a4c972da-b12d-4b4b-8481-79ca320ddab7
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.996104
> 3771 slave.cpp:1495] Got assigned task 3 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.996462
> 3771 slave.cpp:1614] Launching task 3 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997241
> 3771 slave.cpp:5674] Launching executor 3 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3/runs/d9363f0d-645b-468f-947a-8bf97d15a630'
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997406
> 3771 slave.cpp:1840] Queuing task '3' for executor '3' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.997640
> 3777 containerizer.cpp:781] Starting container
> 'd9363f0d-645b-468f-947a-8bf97d15a630' for executor '3' of framework
> 'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
> Aug 9 10:12:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:12.999593
> 3777 provisioner.cpp:294] Provisioning image rootfs
> '/mnt/mesos/provisioner/containers/d9363f0d-645b-468f-947a-8bf97d15a630/backends/copy/rootfses/eeb1b066-94b8-4880-a696-090f830cf2d6'
> for container d9363f0d-645b-468f-947a-8bf97d15a630
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.003924
> 3775 slave.cpp:1495] Got assigned task 4 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.004278
> 3775 slave.cpp:1614] Launching task 4 for framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005383
> 3775 slave.cpp:5674] Launching executor 4 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4/runs/58c398e2-38f9-4389-87ba-8696c9a523eb'
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005681
> 3775 slave.cpp:1840] Queuing task '4' for executor '4' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.005971
> 3772 containerizer.cpp:781] Starting container
> '58c398e2-38f9-4389-87ba-8696c9a523eb' for executor '4' of framework
> 'c9852a23-bc07-422d-8d69-23c167a1924d-0003'
> Aug 9 10:12:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:19.007786
> 3777 provisioner.cpp:294] Provisioning image rootfs
> '/mnt/mesos/provisioner/containers/58c398e2-38f9-4389-87ba-8696c9a523eb/backends/copy/rootfses/78b7bf3a-6f3b-4f8a-b4fa-98a6ea4bf8f5'
> for container 58c398e2-38f9-4389-87ba-8696c9a523eb
> Aug 9 10:12:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:41.801560
> 3777 slave.cpp:4591] Current disk usage 43.28%. Max allowed age:
> 3.270091966898553days
> Aug 9 10:12:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:54.972057
> 3772 slave.cpp:4539] Terminating executor ''0' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
> 1mins
> Aug 9 10:12:54 ip-172-31-23-17 mesos-slave[3738]: I0809 10:12:54.972322
> 3775 containerizer.cpp:1622] Destroying container
> '09a77a80-f904-4581-966a-734d24ae96f3'
> Aug 9 10:13:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:00.979907
> 3777 slave.cpp:4539] Terminating executor ''1' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
> 1mins
> Aug 9 10:13:00 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:00.980058
> 3775 containerizer.cpp:1622] Destroying container
> '0cea1dc7-b523-4b68-851a-531ed101de5a'
> Aug 9 10:13:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:06.985003
> 3777 slave.cpp:4539] Terminating executor ''2' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
> 1mins
> Aug 9 10:13:06 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:06.985255
> 3774 containerizer.cpp:1622] Destroying container
> 'a4c972da-b12d-4b4b-8481-79ca320ddab7'
> Aug 9 10:13:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:12.998438
> 3777 slave.cpp:4539] Terminating executor ''3' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
> 1mins
> Aug 9 10:13:12 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:12.998641
> 3776 containerizer.cpp:1622] Destroying container
> 'd9363f0d-645b-468f-947a-8bf97d15a630'
> Aug 9 10:13:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:19.006058
> 3773 slave.cpp:4539] Terminating executor ''4' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003' because it did not register within
> 1mins
> Aug 9 10:13:19 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:19.006228
> 3773 containerizer.cpp:1622] Destroying container
> '58c398e2-38f9-4389-87ba-8696c9a523eb'
> Aug 9 10:13:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:13:41.802209
> 3775 slave.cpp:4591] Current disk usage 57.59%. Max allowed age:
> 2.269006805055764days
> Aug 9 10:14:41 ip-172-31-23-17 mesos-slave[3738]: I0809 10:14:41.803022
> 3776 slave.cpp:4591] Current disk usage 68.70%. Max allowed age:
> 1.491204118572176days
> Aug 9 10:15:01 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:01.516207
> 3775 slave.cpp:3976] Container '58c398e2-38f9-4389-87ba-8696c9a523eb' for
> executor '4' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to
> start: Container is being destroyed during provisioning
> Aug 9 10:15:01 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:01.518277
> 3772 composing.cpp:541] Container '58c398e2-38f9-4389-87ba-8696c9a523eb' is
> already destroyed
> Aug 9 10:15:02 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:02.291764
> 3775 slave.cpp:3976] Container '09a77a80-f904-4581-966a-734d24ae96f3' for
> executor '0' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to
> start: Container is being destroyed during provisioning
> Aug 9 10:15:02 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:02.291877
> 3775 composing.cpp:541] Container '09a77a80-f904-4581-966a-734d24ae96f3' is
> already destroyed
> Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:03.022406
> 3774 slave.cpp:3976] Container 'd9363f0d-645b-468f-947a-8bf97d15a630' for
> executor '3' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to
> start: Container is being destroyed during provisioning
> Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:03.022595
> 3771 composing.cpp:541] Container 'd9363f0d-645b-468f-947a-8bf97d15a630' is
> already destroyed
> Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:03.461519
> 3775 slave.cpp:3976] Container '0cea1dc7-b523-4b68-851a-531ed101de5a' for
> executor '1' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to
> start: Container is being destroyed during provisioning
> Aug 9 10:15:03 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:03.461643
> 3774 composing.cpp:541] Container '0cea1dc7-b523-4b68-851a-531ed101de5a' is
> already destroyed
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.190901
> 3776 provisioner.cpp:434] Destroying container rootfs at
> '/mnt/mesos/provisioner/containers/58c398e2-38f9-4389-87ba-8696c9a523eb/backends/copy/rootfses/78b7bf3a-6f3b-4f8a-b4fa-98a6ea4bf8f5'
> for container 58c398e2-38f9-4389-87ba-8696c9a523eb
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: E0809 10:15:04.190904
> 3777 slave.cpp:3976] Container 'a4c972da-b12d-4b4b-8481-79ca320ddab7' for
> executor '2' of framework c9852a23-bc07-422d-8d69-23c167a1924d-0003 failed to
> start: Container is being destroyed during provisioning
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:04.191128
> 3770 composing.cpp:541] Container 'a4c972da-b12d-4b4b-8481-79ca320ddab7' is
> already destroyed
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197221
> 3776 provisioner.cpp:434] Destroying container rootfs at
> '/mnt/mesos/provisioner/containers/09a77a80-f904-4581-966a-734d24ae96f3/backends/copy/rootfses/ec9bb10e-6726-4872-be2b-4a72dfc0fafc'
> for container 09a77a80-f904-4581-966a-734d24ae96f3
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197410
> 3776 provisioner.cpp:434] Destroying container rootfs at
> '/mnt/mesos/provisioner/containers/d9363f0d-645b-468f-947a-8bf97d15a630/backends/copy/rootfses/eeb1b066-94b8-4880-a696-090f830cf2d6'
> for container d9363f0d-645b-468f-947a-8bf97d15a630
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197510
> 3776 provisioner.cpp:434] Destroying container rootfs at
> '/mnt/mesos/provisioner/containers/0cea1dc7-b523-4b68-851a-531ed101de5a/backends/copy/rootfses/e3af89fe-c889-45fc-929b-f008b18e5d4e'
> for container 0cea1dc7-b523-4b68-851a-531ed101de5a
> Aug 9 10:15:04 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:04.197619
> 3776 provisioner.cpp:434] Destroying container rootfs at
> '/mnt/mesos/provisioner/containers/a4c972da-b12d-4b4b-8481-79ca320ddab7/backends/copy/rootfses/feaf70a4-df43-45c9-b6be-a654c9210a54'
> for container a4c972da-b12d-4b4b-8481-79ca320ddab7
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.390928
> 3776 slave.cpp:4082] Executor '3' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.395370
> 3776 slave.cpp:3211] Handling status update TASK_FAILED (UUID:
> 543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.402472
> 3776 slave.cpp:4082] Executor '2' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.402556
> 3776 slave.cpp:3211] Handling status update TASK_FAILED (UUID:
> 1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.406177
> 3772 containerizer.cpp:1451] Ignoring update for unknown container:
> d9363f0d-645b-468f-947a-8bf97d15a630
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.407325
> 3777 status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
> 543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.407598
> 3773 containerizer.cpp:1451] Ignoring update for unknown container:
> a4c972da-b12d-4b4b-8481-79ca320ddab7
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.409890
> 3774 slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
> 543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.409893
> 3777 status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
> 1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.414727
> 3774 slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
> 1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.418180
> 3777 status_update_manager.cpp:392] Received status update acknowledgement
> (UUID: 543e1e1e-ae47-48ab-ad64-ee82fdd27418) for task 3 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419615
> 3777 status_update_manager.cpp:392] Received status update acknowledgement
> (UUID: 1a54664d-4157-42a3-a97f-7189c137a769) for task 2 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419636
> 3774 slave.cpp:4193] Cleaning up executor '3' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.419924
> 3772 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3/runs/d9363f0d-645b-468f-947a-8bf97d15a630'
> for gc 6.9999951410637days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420703
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/3'
> for gc 6.99999513154074days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420722
> 3774 slave.cpp:4193] Cleaning up executor '2' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420897
> 3774 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2/runs/a4c972da-b12d-4b4b-8481-79ca320ddab7'
> for gc 6.99999512931852days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.420940
> 3774 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/2'
> for gc 6.99999512869333days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490155
> 3772 slave.cpp:4082] Executor '4' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490289
> 3772 slave.cpp:3211] Handling status update TASK_FAILED (UUID:
> 9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490414
> 3772 slave.cpp:4082] Executor '0' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490489
> 3772 slave.cpp:3211] Handling status update TASK_FAILED (UUID:
> 3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.490687
> 3773 containerizer.cpp:1451] Ignoring update for unknown container:
> 58c398e2-38f9-4389-87ba-8696c9a523eb
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.490774
> 3773 containerizer.cpp:1451] Ignoring update for unknown container:
> 09a77a80-f904-4581-966a-734d24ae96f3
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.490867
> 3773 status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
> 9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491082
> 3773 status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
> 3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491082
> 3772 slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
> 9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.491303
> 3774 slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
> 3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494438
> 3775 status_update_manager.cpp:392] Received status update acknowledgement
> (UUID: 9c5528ec-0725-42f2-a2e2-465d3c73b8d5) for task 4 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494572
> 3775 slave.cpp:4193] Cleaning up executor '4' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494757
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4/runs/58c398e2-38f9-4389-87ba-8696c9a523eb'
> for gc 6.99999427436444days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494817
> 3775 status_update_manager.cpp:392] Received status update acknowledgement
> (UUID: 3f801210-b029-427f-af3f-e51b1dfa5cc8) for task 0 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494937
> 3775 slave.cpp:4193] Cleaning up executor '0' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.494823
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/4'
> for gc 6.99999427361185days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.495107
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0/runs/09a77a80-f904-4581-966a-734d24ae96f3'
> for gc 6.99999427055407days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.495149
> 3773 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/0'
> for gc 6.99999427024296days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591338
> 3775 slave.cpp:4082] Executor '1' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 has terminated with unknown status
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591469
> 3775 slave.cpp:3211] Handling status update TASK_FAILED (UUID:
> 85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 from @0.0.0.0:0
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: W0809 10:15:07.591841
> 3776 containerizer.cpp:1451] Ignoring update for unknown container:
> 0cea1dc7-b523-4b68-851a-531ed101de5a
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.591974
> 3770 status_update_manager.cpp:320] Received status update TASK_FAILED (UUID:
> 85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.592157
> 3772 slave.cpp:3604] Forwarding the update TASK_FAILED (UUID:
> 85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003 to [email protected]:5050
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594518
> 3771 status_update_manager.cpp:392] Received status update acknowledgement
> (UUID: 85b7c18a-c59c-4449-827c-a3bfaa2a7de8) for task 1 of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594636
> 3771 slave.cpp:4193] Cleaning up executor '1' of framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594792
> 3770 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1/runs/0cea1dc7-b523-4b68-851a-531ed101de5a'
> for gc 6.99999311635556days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594820
> 3771 slave.cpp:4281] Cleaning up framework
> c9852a23-bc07-422d-8d69-23c167a1924d-0003
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594861
> 3770 gc.cpp:55] Scheduling
> '/mnt/mesos/slaves/c9852a23-bc07-422d-8d69-23c167a1924d-S1/frameworks/c9852a23-bc07-422d-8d69-23c167a1924d-0003/executors/1'
> for gc 6.99999311568296days in the future
> Aug 9 10:15:07 ip-172-31-23-17 mesos-slave[3738]: I0809 10:15:07.594897
> 3770 status_update_manager.cpp:282] Closing status update streams for
> framework c9852a23-bc07-422d-8d69-23c167a1924d-0003
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
