mesos-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Alexander Rukletsov (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (MESOS-7082) ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0 is flaky.
Date Wed, 04 Oct 2017 16:14:00 GMT

     [ https://issues.apache.org/jira/browse/MESOS-7082?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Alexander Rukletsov updated MESOS-7082:
---------------------------------------
    Summary: ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0 is flaky.  (was: ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0 is flaky)

> ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0 is flaky.
> ---------------------------------------------------------------------------------
>
>                 Key: MESOS-7082
>                 URL: https://issues.apache.org/jira/browse/MESOS-7082
>             Project: Mesos
>          Issue Type: Bug
>    Affects Versions: 1.2.0
>         Environment: ubuntu 16.04 with/without SSL
> Fedora 23
>            Reporter: Anand Mazumdar
>            Priority: Critical
>              Labels: flaky, flaky-test, mesosphere
>
> Showed up on our internal CI
> {noformat}
> 07:00:17 [ RUN      ] ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0
> 07:00:17 I0207 07:00:17.775459  2952 cluster.cpp:160] Creating default 'local' authorizer
> 07:00:17 I0207 07:00:17.776511  2970 master.cpp:383] Master fa1554c4-572a-4b89-8994-a89460f588d3 (ip-10-153-254-29.ec2.internal) started on 10.153.254.29:38570
> 07:00:17 I0207 07:00:17.776538  2970 master.cpp:385] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ZROfJk/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/ZROfJk/master" --zk_session_timeout="10secs"
> 07:00:17 I0207 07:00:17.776674  2970 master.cpp:435] Master only allowing authenticated frameworks to register
> 07:00:17 I0207 07:00:17.776687  2970 master.cpp:449] Master only allowing authenticated agents to register
> 07:00:17 I0207 07:00:17.776695  2970 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
> 07:00:17 I0207 07:00:17.776703  2970 credentials.hpp:37] Loading credentials for authentication from '/tmp/ZROfJk/credentials'
> 07:00:17 I0207 07:00:17.776779  2970 master.cpp:507] Using default 'crammd5' authenticator
> 07:00:17 I0207 07:00:17.776841  2970 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
> 07:00:17 I0207 07:00:17.776919  2970 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
> 07:00:17 I0207 07:00:17.776970  2970 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
> 07:00:17 I0207 07:00:17.777009  2970 master.cpp:587] Authorization enabled
> 07:00:17 I0207 07:00:17.777122  2975 hierarchical.cpp:161] Initialized hierarchical allocator process
> 07:00:17 I0207 07:00:17.777138  2974 whitelist_watcher.cpp:77] No whitelist given
> 07:00:17 I0207 07:00:17.777704  2976 master.cpp:2123] Elected as the leading master!
> 07:00:17 I0207 07:00:17.777726  2976 master.cpp:1645] Recovering from registrar
> 07:00:17 I0207 07:00:17.777784  2975 registrar.cpp:329] Recovering registrar
> 07:00:17 I0207 07:00:17.777989  2973 registrar.cpp:362] Successfully fetched the registry (0B) in 176384ns
> 07:00:17 I0207 07:00:17.778023  2973 registrar.cpp:461] Applied 1 operations in 7573ns; attempting to update the registry
> 07:00:17 I0207 07:00:17.778249  2976 registrar.cpp:506] Successfully updated the registry in 210944ns
> 07:00:17 I0207 07:00:17.778290  2976 registrar.cpp:392] Successfully recovered registrar
> 07:00:17 I0207 07:00:17.778373  2976 master.cpp:1761] Recovered 0 agents from the registry (172B); allowing 10mins for agents to re-register
> 07:00:17 I0207 07:00:17.778394  2974 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
> 07:00:17 I0207 07:00:17.869381  2952 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
> 07:00:17 I0207 07:00:17.872557  2952 linux_launcher.cpp:150] Using /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
> 07:00:17 I0207 07:00:17.872915  2952 provisioner.cpp:249] Using default backend 'overlay'
> 07:00:17 I0207 07:00:17.873425  2952 cluster.cpp:446] Creating default 'local' authorizer
> 07:00:17 I0207 07:00:17.873791  2974 slave.cpp:211] Mesos agent started on (716)@10.153.254.29:38570
> 07:00:17 I0207 07:00:17.874034  2952 scheduler.cpp:184] Version: 1.2.0
> 07:00:17 I0207 07:00:17.873829  2974 slave.cpp:212] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --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="docker,mesos" --credential="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_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/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="linux" --launcher_dir="/home/ubuntu/workspace/mesos/Mesos_CI-build/FLAG/Clang/label/mesos-ec2-ubuntu-16.04/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4"
> 07:00:17 I0207 07:00:17.874168  2974 credentials.hpp:86] Loading credential for authentication from '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/credential'
> 07:00:17 I0207 07:00:17.874236  2974 slave.cpp:354] Agent using credential for: test-principal
> 07:00:17 I0207 07:00:17.874258  2974 credentials.hpp:37] Loading credentials for authentication from '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/http_credentials'
> 07:00:17 I0207 07:00:17.874331  2974 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
> 07:00:17 I0207 07:00:17.874583  2976 scheduler.cpp:470] New master detected at master@10.153.254.29:38570
> 07:00:17 I0207 07:00:17.874610  2976 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
> 07:00:17 I0207 07:00:17.874836  2974 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> 07:00:17 I0207 07:00:17.874873  2974 slave.cpp:549] Agent attributes: [  ]
> 07:00:17 I0207 07:00:17.874881  2974 slave.cpp:554] Agent hostname: ip-10-153-254-29.ec2.internal
> 07:00:17 I0207 07:00:17.874991  2970 status_update_manager.cpp:177] Pausing sending status updates
> 07:00:17 I0207 07:00:17.875248  2972 state.cpp:62] Recovering state from '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/meta'
> 07:00:17 I0207 07:00:17.875445  2971 status_update_manager.cpp:203] Recovering status update manager
> 07:00:17 I0207 07:00:17.875591  2972 containerizer.cpp:599] Recovering containerizer
> 07:00:17 I0207 07:00:17.875604  2977 docker.cpp:868] Recovering Docker containers
> 07:00:17 I0207 07:00:17.875658  2977 docker.cpp:1139] Running docker -H unix:///var/run/docker.sock ps -a
> 07:00:17 I0207 07:00:17.879174  2973 scheduler.cpp:361] Connected with the master at http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:17 I0207 07:00:17.879809  2976 provisioner.cpp:410] Provisioner recovery complete
> 07:00:17 I0207 07:00:17.879889  2971 scheduler.cpp:243] Sending SUBSCRIBE call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:17 I0207 07:00:17.880321  2976 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:17 I0207 07:00:17.880755  2972 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44396
> 07:00:17 I0207 07:00:17.880834  2972 master.cpp:2422] Received subscription request for HTTP framework 'default'
> 07:00:17 I0207 07:00:17.880857  2972 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
> 07:00:17 I0207 07:00:17.881100  2977 master.cpp:2536] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
> 07:00:17 I0207 07:00:17.881320  2976 master.hpp:2114] Sending heartbeat to fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:17 I0207 07:00:17.881291  2973 hierarchical.cpp:286] Added framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:17 I0207 07:00:17.881448  2973 hierarchical.cpp:1817] No allocations performed
> 07:00:17 I0207 07:00:17.881471  2973 hierarchical.cpp:1907] No inverse offers to send out!
> 07:00:17 I0207 07:00:17.881480  2973 hierarchical.cpp:1401] Performed allocation for 0 agents in 47645ns
> 07:00:17 I0207 07:00:17.881830  2972 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:17 I0207 07:00:17.882037  2972 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:17.970202  2975 slave.cpp:5499] Finished recovery
> 07:00:18 I0207 07:00:17.970434  2975 slave.cpp:5673] Querying resource estimator for oversubscribable resources
> 07:00:18 I0207 07:00:17.970556  2975 slave.cpp:931] New master detected at master@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.970564  2973 status_update_manager.cpp:177] Pausing sending status updates
> 07:00:18 I0207 07:00:17.970598  2975 slave.cpp:966] Detecting new master
> 07:00:18 I0207 07:00:17.970636  2975 slave.cpp:5687] Received oversubscribable resources {} from the resource estimator
> 07:00:18 I0207 07:00:17.976898  2970 slave.cpp:993] Authenticating with master master@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.976929  2970 slave.cpp:1004] Using default CRAM-MD5 authenticatee
> 07:00:18 I0207 07:00:17.977015  2974 authenticatee.cpp:121] Creating new client SASL connection
> 07:00:18 I0207 07:00:17.977448  2974 master.cpp:7103] Authenticating slave(716)@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.977509  2975 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1366)@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.977602  2976 authenticator.cpp:98] Creating new server SASL connection
> 07:00:18 I0207 07:00:17.977922  2976 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
> 07:00:18 I0207 07:00:17.977954  2976 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
> 07:00:18 I0207 07:00:17.977998  2976 authenticator.cpp:204] Received SASL authentication start
> 07:00:18 I0207 07:00:17.978061  2976 authenticator.cpp:326] Authentication requires more steps
> 07:00:18 I0207 07:00:17.978106  2976 authenticatee.cpp:259] Received SASL authentication step
> 07:00:18 I0207 07:00:17.978184  2974 authenticator.cpp:232] Received SASL authentication step
> 07:00:18 I0207 07:00:17.978210  2974 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-10-153-254-29.ec2.internal' server FQDN: 'ip-10-153-254-29.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
> 07:00:18 I0207 07:00:17.978219  2974 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
> 07:00:18 I0207 07:00:17.978227  2974 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
> 07:00:18 I0207 07:00:17.978235  2974 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ip-10-153-254-29.ec2.internal' server FQDN: 'ip-10-153-254-29.ec2.internal' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
> 07:00:18 I0207 07:00:17.978238  2974 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 07:00:18 I0207 07:00:17.978243  2974 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 07:00:18 I0207 07:00:17.978252  2974 authenticator.cpp:318] Authentication success
> 07:00:18 I0207 07:00:17.978297  2973 authenticatee.cpp:299] Authentication success
> 07:00:18 I0207 07:00:17.978324  2974 master.cpp:7133] Successfully authenticated principal 'test-principal' at slave(716)@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.978356  2972 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1366)@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.978426  2975 slave.cpp:1088] Successfully authenticated with master master@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.978520  2975 slave.cpp:1515] Will retry registration in 19.358366ms if necessary
> 07:00:18 I0207 07:00:17.978575  2973 master.cpp:5318] Registering agent at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) with id fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:18 I0207 07:00:17.978699  2976 registrar.cpp:461] Applied 1 operations in 24121ns; attempting to update the registry
> 07:00:18 I0207 07:00:17.979012  2974 registrar.cpp:506] Successfully updated the registry in 280832ns
> 07:00:18 I0207 07:00:17.979252  2973 slave.cpp:4301] Received ping from slave-observer(652)@10.153.254.29:38570
> 07:00:18 I0207 07:00:17.979238  2976 master.cpp:5392] Registered agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> 07:00:18 I0207 07:00:17.979311  2973 slave.cpp:1134] Registered with master master@10.153.254.29:38570; given agent ID fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:18 I0207 07:00:17.979312  2974 hierarchical.cpp:515] Added agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 (ip-10-153-254-29.ec2.internal) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> 07:00:18 I0207 07:00:17.979336  2973 fetcher.cpp:90] Clearing fetcher cache
> 07:00:18 I0207 07:00:17.979425  2977 status_update_manager.cpp:184] Resuming sending status updates
> 07:00:18 I0207 07:00:17.979490  2973 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/meta/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/slave.info'
> 07:00:18 I0207 07:00:17.979542  2974 hierarchical.cpp:1907] No inverse offers to send out!
> 07:00:18 I0207 07:00:17.979562  2974 hierarchical.cpp:1401] Performed allocation for 1 agents in 198598ns
> 07:00:18 I0207 07:00:17.979625  2973 slave.cpp:1200] Forwarding total oversubscribed resources {}
> 07:00:18 I0207 07:00:17.979665  2970 master.cpp:6932] Sending 1 offers to framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:17.979815  2970 master.cpp:5949] Received update of agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) with total oversubscribed resources {}
> 07:00:18 I0207 07:00:17.979897  2970 hierarchical.cpp:588] Agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 (ip-10-153-254-29.ec2.internal) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
> 07:00:18 I0207 07:00:17.979951  2970 hierarchical.cpp:1817] No allocations performed
> 07:00:18 I0207 07:00:17.980002  2970 hierarchical.cpp:1907] No inverse offers to send out!
> 07:00:18 I0207 07:00:17.980016  2970 hierarchical.cpp:1401] Performed allocation for 1 agents in 83177ns
> 07:00:18 I0207 07:00:17.980195  2974 scheduler.cpp:676] Enqueuing event OFFERS received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:17.980867  2970 scheduler.cpp:243] Sending ACCEPT call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:17.981184  2973 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:17.981575  2976 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:17.981762  2976 master.cpp:3745] Processing ACCEPT call for offers: [ fa1554c4-572a-4b89-8994-a89460f588d3-O0 ] on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:17.981812  2976 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb
> 07:00:18 I0207 07:00:17.981945  2976 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task c24a618d-ce08-4784-b8c7-0eeda79d121b
> 07:00:18 I0207 07:00:17.982826  2976 master.cpp:8993] Adding task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:17.982899  2976 master.cpp:8993] Adding task c24a618d-ce08-4784-b8c7-0eeda79d121b with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:17.982950  2976 master.cpp:4600] Launching task group { 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b } of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) with resources cpus(*)(allocated: *):0.2; mem(*)(allocated: *):64; disk(*)(allocated: *):64 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:17.983122  2977 slave.cpp:1583] Got assigned task group containing tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ] for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:17.983191  2973 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):1.7; mem(*)(allocated: *):928; disk(*)(allocated: *):928; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.3; mem(*)(allocated: *):96; disk(*)(allocated: *):96) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:17.983423  2977 slave.cpp:1743] Launching task group containing tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ] for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:17.983650  2977 paths.cpp:547] Trying to chown '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974' to user 'root'
> 07:00:18 I0207 07:00:17.983779  2977 slave.cpp:6414] Launching executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 in work directory '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974'
> 07:00:18 I0207 07:00:17.983988  2977 slave.cpp:2073] Queued task group containing tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ] for executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:17.984022  2974 docker.cpp:1099] No container info found, skipping launch
> 07:00:18 I0207 07:00:17.984032  2977 slave.cpp:884] Successfully attached file '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974'
> 07:00:18 I0207 07:00:17.984192  2975 containerizer.cpp:992] Starting container 4cfc866d-9fef-442b-8682-58dabb305974 for executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:17.985002  2976 containerizer.cpp:1538] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-default-executor","--launcher_dir=\/home\/ubuntu\/workspace\/mesos\/Mesos_CI-build\/FLAG\/Clang\/label\/mesos-ec2-ubuntu-16.04\/mesos\/build\/src"],"shell":false,"value":"\/home\/ubuntu\/workspace\/mesos\/Mesos_CI-build\/FLAG\/Clang\/label\/mesos-ec2-ubuntu-16.04\/mesos\/build\/src\/mesos-default-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"10.153.254.29:38570"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974"},{"name":"MESOS_EXECUTOR_ID","value":"default"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"fa1554c4-572a-4b89-8994-a89460f588d3-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"fa1554c4-572a-4b89-8994-a89460f588d3-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(716)@10.153.254.29:38570"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974"}]},"user":"root","working_directory":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974"}" --pipe_read="30" --pipe_write="31" --runtime_directory="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974" --unshare_namespace_mnt="false"'
> 07:00:18 I0207 07:00:17.985275  2970 linux_launcher.cpp:429] Launching container 4cfc866d-9fef-442b-8682-58dabb305974 and cloning with namespaces 
> 07:00:18 I0207 07:00:18.011076  2973 fetcher.cpp:349] Starting to fetch URIs for container: 4cfc866d-9fef-442b-8682-58dabb305974, directory: /tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:18 I0207 07:00:18.076251 29005 executor.cpp:192] Version: 1.2.0
> 07:00:18 I0207 07:00:18.078815  2974 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:18 I0207 07:00:18.079205  2975 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44400
> 07:00:18 I0207 07:00:18.079286  2975 slave.cpp:3124] Received Subscribe request for HTTP executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.080021  2970 slave.cpp:2315] Sending queued task group task group containing tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ] to executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (via HTTP)
> 07:00:18 I0207 07:00:18.080541 29024 default_executor.cpp:153] Received SUBSCRIBED event
> 07:00:18 I0207 07:00:18.082542 29024 default_executor.cpp:157] Subscribed executor on ip-10-153-254-29.ec2.internal
> 07:00:18 I0207 07:00:18.082628 29024 default_executor.cpp:153] Received LAUNCH_GROUP event
> 07:00:18 I0207 07:00:18.083161  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.083336  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.083618  2976 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44404
> 07:00:18 I0207 07:00:18.083688  2976 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44404
> 07:00:18 I0207 07:00:18.083775  2976 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.083876  2976 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.084066  2972 containerizer.cpp:1805] Starting nested container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.084185  2972 containerizer.cpp:1829] Trying to chown '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/966d71b2-cbc7-40a6-accf-bef80d3d8338' to user 'root'
> 07:00:18 I0207 07:00:18.084568  2972 containerizer.cpp:1805] Starting nested container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:18 I0207 07:00:18.084655  2972 containerizer.cpp:1829] Trying to chown '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/486b0abc-966d-49b7-b2e0-58cd1d4b462e' to user 'root'
> 07:00:18 I0207 07:00:18.085088  2972 containerizer.cpp:1538] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/966d71b2-cbc7-40a6-accf-bef80d3d8338"}]},"user":"root","working_directory":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/966d71b2-cbc7-40a6-accf-bef80d3d8338"}" --pipe_read="57" --pipe_write="76" --runtime_directory="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/966d71b2-cbc7-40a6-accf-bef80d3d8338" --unshare_namespace_mnt="false"'
> 07:00:18 I0207 07:00:18.085297  2976 linux_launcher.cpp:429] Launching nested container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338 and cloning with namespaces 
> 07:00:18 I0207 07:00:18.114851  2972 containerizer.cpp:1538] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/486b0abc-966d-49b7-b2e0-58cd1d4b462e"}]},"user":"root","working_directory":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/486b0abc-966d-49b7-b2e0-58cd1d4b462e"}" --pipe_read="77" --pipe_write="78" --runtime_directory="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/486b0abc-966d-49b7-b2e0-58cd1d4b462e" --unshare_namespace_mnt="false"'
> 07:00:18 I0207 07:00:18.115092  2974 linux_launcher.cpp:429] Launching nested container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e and cloning with namespaces 
> 07:00:18 I0207 07:00:18.124974  2970 fetcher.cpp:349] Starting to fetch URIs for container: 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338, directory: /tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.126298  2970 fetcher.cpp:349] Starting to fetch URIs for container: 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e, directory: /tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:18 I0207 07:00:18.129250 29022 default_executor.cpp:469] Successfully launched tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ] in child containers [ 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338, 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e ]
> 07:00:18 I0207 07:00:18.130044 29019 default_executor.cpp:542] Waiting for child container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338 of task '16b247b5-e1dd-4395-a2d7-71c9c393e5bb'
> 07:00:18 I0207 07:00:18.130199 29019 default_executor.cpp:542] Waiting for child container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e of task 'c24a618d-ce08-4784-b8c7-0eeda79d121b'
> 07:00:18 I0207 07:00:18.130437  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:18 I0207 07:00:18.130678  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:18 I0207 07:00:18.130791  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.130939  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.131260  2973 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44408
> 07:00:18 I0207 07:00:18.131341  2973 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:18 I0207 07:00:18.131402  2973 slave.cpp:3771] Handling status update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.131579  2973 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44406
> 07:00:18 I0207 07:00:18.131680  2973 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:18 I0207 07:00:18.131752  2973 slave.cpp:3771] Handling status update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132120  2973 http.cpp:517] Processing call WAIT_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.132252  2973 http.cpp:517] Processing call WAIT_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.132444  2976 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132472  2976 status_update_manager.cpp:500] Creating StatusUpdate stream for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132637  2976 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:18 I0207 07:00:18.132741  2973 slave.cpp:4211] Forwarding the update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:18 I0207 07:00:18.132776  2976 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132791  2976 status_update_manager.cpp:500] Creating StatusUpdate stream for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132839  2973 slave.cpp:4105] Status update manager successfully handled status update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.132913  2976 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:18 I0207 07:00:18.132949  2972 master.cpp:6094] Status update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:18.133024  2974 slave.cpp:4211] Forwarding the update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:18 I0207 07:00:18.133051  2972 master.cpp:6162] Forwarding status update TASK_RUNNING (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.133170  2974 slave.cpp:4105] Status update manager successfully handled status update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.133327 29025 default_executor.cpp:153] Received ACKNOWLEDGED event
> 07:00:18 I0207 07:00:18.133405  2972 master.cpp:8242] Updating the state of task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
> 07:00:18 I0207 07:00:18.133518  2972 master.cpp:6094] Status update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:18.133553  2972 master.cpp:6162] Forwarding status update TASK_RUNNING (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.133646  2972 master.cpp:8242] Updating the state of task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
> 07:00:18 I0207 07:00:18.133905 29019 default_executor.cpp:153] Received ACKNOWLEDGED event
> 07:00:18 I0207 07:00:18.134141  2973 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.134351  2973 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.778179  2976 hierarchical.cpp:1907] No inverse offers to send out!
> 07:00:18 I0207 07:00:18.778226  2976 hierarchical.cpp:1401] Performed allocation for 1 agents in 258865ns
> 07:00:18 I0207 07:00:18.778333  2972 master.cpp:6932] Sending 1 offers to framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:18.778885  2972 scheduler.cpp:676] Enqueuing event OFFERS received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.779575  2976 scheduler.cpp:243] Sending ACCEPT call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.779938  2976 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:18.817811  2974 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:18.817988  2974 master.cpp:3745] Processing ACCEPT call for offers: [ fa1554c4-572a-4b89-8994-a89460f588d3-O1 ] on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:18.818032  2974 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 8cd47a02-ff21-4585-ab72-0ce803a4be78
> 07:00:18 I0207 07:00:18.818761  2970 master.cpp:8993] Adding task 8cd47a02-ff21-4585-ab72-0ce803a4be78 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:18.818826  2970 master.cpp:4600] Launching task group { 8cd47a02-ff21-4585-ab72-0ce803a4be78 } of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:18.818967  2976 slave.cpp:1583] Got assigned task group containing tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ] for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.819067  2971 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):1.6; mem(*)(allocated: *):896; disk(*)(allocated: *):896; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.4; mem(*)(allocated: *):128; disk(*)(allocated: *):128) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.819103  2971 hierarchical.cpp:1116] Framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 filtered agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 for 5secs
> 07:00:18 I0207 07:00:18.819105  2976 slave.cpp:1743] Launching task group containing tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ] for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.819150  2976 slave.cpp:2095] Queued task group containing tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ] for executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (via HTTP)
> 07:00:18 I0207 07:00:18.819540  2974 slave.cpp:2315] Sending queued task group task group containing tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ] to executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (via HTTP)
> 07:00:18 I0207 07:00:18.820272 29019 default_executor.cpp:153] Received LAUNCH_GROUP event
> 07:00:18 I0207 07:00:18.821336  2970 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.821585  2972 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44410
> 07:00:18 I0207 07:00:18.821693  2972 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.821916  2976 containerizer.cpp:1805] Starting nested container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:18 I0207 07:00:18.822005  2976 containerizer.cpp:1829] Trying to chown '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/5e04c992-3a62-4187-86b2-2ad4abb2f5e8' to user 'root'
> 07:00:18 I0207 07:00:18.822497  2975 containerizer.cpp:1538] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/5e04c992-3a62-4187-86b2-2ad4abb2f5e8"}]},"user":"root","working_directory":"\/tmp\/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4\/slaves\/fa1554c4-572a-4b89-8994-a89460f588d3-S0\/frameworks\/fa1554c4-572a-4b89-8994-a89460f588d3-0000\/executors\/default\/runs\/4cfc866d-9fef-442b-8682-58dabb305974\/containers\/5e04c992-3a62-4187-86b2-2ad4abb2f5e8"}" --pipe_read="77" --pipe_write="78" --runtime_directory="/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/5e04c992-3a62-4187-86b2-2ad4abb2f5e8" --unshare_namespace_mnt="false"'
> 07:00:18 I0207 07:00:18.822722  2977 linux_launcher.cpp:429] Launching nested container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 and cloning with namespaces 
> 07:00:18 I0207 07:00:18.851102  2976 fetcher.cpp:349] Starting to fetch URIs for container: 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8, directory: /tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974/containers/5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:18 I0207 07:00:18.853680 29021 default_executor.cpp:469] Successfully launched tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ] in child containers [ 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 ]
> 07:00:18 I0207 07:00:18.854037 29026 default_executor.cpp:542] Waiting for child container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 of task '8cd47a02-ff21-4585-ab72-0ce803a4be78'
> 07:00:18 I0207 07:00:18.854059  2973 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:18 I0207 07:00:18.854372  2976 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.854599  2974 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44412
> 07:00:18 I0207 07:00:18.854703  2974 http.cpp:517] Processing call WAIT_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.893777  2973 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:18 I0207 07:00:18.893868  2973 slave.cpp:3771] Handling status update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.894559  2970 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.894589  2970 status_update_manager.cpp:500] Creating StatusUpdate stream for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.894749  2970 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:18 I0207 07:00:18.894865  2977 slave.cpp:4211] Forwarding the update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:18 I0207 07:00:18.894951  2977 slave.cpp:4105] Status update manager successfully handled status update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.895066  2975 master.cpp:6094] Status update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:18 I0207 07:00:18.895097  2975 master.cpp:6162] Forwarding status update TASK_RUNNING (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.895196  2975 master.cpp:8242] Updating the state of task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
> 07:00:18 I0207 07:00:18.895474 29025 default_executor.cpp:153] Received ACKNOWLEDGED event
> 07:00:18 I0207 07:00:18.895576  2971 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.896118  2972 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.896244  2972 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.896327  2972 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.896453  2972 scheduler.cpp:243] Sending KILL call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:18 I0207 07:00:18.896493  2975 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:18.933679  2976 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:18.933753  2976 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:18.933818  2976 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:18 I0207 07:00:18.934253  2976 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:18.934309  2976 master.cpp:5032] Processing ACKNOWLEDGE call d54c9399-1a59-426c-80f2-c5fa491b9215 for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:18 I0207 07:00:18.934440  2974 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.934512  2976 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:18.934556  2976 master.cpp:5032] Processing ACKNOWLEDGE call 10681538-b4e6-4eb8-b8ae-0e7af9b4e009 for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:18 I0207 07:00:18.934666  2973 slave.cpp:3060] Status update manager successfully handled status update acknowledgement (UUID: d54c9399-1a59-426c-80f2-c5fa491b9215) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.934710  2976 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:18.934746  2973 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.934763  2976 master.cpp:5032] Processing ACKNOWLEDGE call 128da3e5-7a2c-4351-a36c-5dd43d7977ea for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:18 I0207 07:00:18.934803  2973 slave.cpp:3060] Status update manager successfully handled status update acknowledgement (UUID: 10681538-b4e6-4eb8-b8ae-0e7af9b4e009) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.934844  2976 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:18 I0207 07:00:18.934875  2976 master.cpp:4851] Processing KILL call for task '16b247b5-e1dd-4395-a2d7-71c9c393e5bb' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:18.934918  2973 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.934916  2976 master.cpp:4924] Telling agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) to kill task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:18 I0207 07:00:18.935019  2975 slave.cpp:2383] Asked to kill task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.935169  2975 slave.cpp:3060] Status update manager successfully handled status update acknowledgement (UUID: 128da3e5-7a2c-4351-a36c-5dd43d7977ea) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:18 I0207 07:00:18.935544 29020 default_executor.cpp:153] Received KILL event
> 07:00:18 I0207 07:00:18.935567 29020 default_executor.cpp:841] Received kill for task '16b247b5-e1dd-4395-a2d7-71c9c393e5bb'
> 07:00:18 I0207 07:00:18.935581 29020 default_executor.cpp:813] Killing child container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.936013  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:18 I0207 07:00:18.936257  2976 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44414
> 07:00:18 I0207 07:00:18.936349  2976 http.cpp:517] Processing call KILL_NESTED_CONTAINER
> 07:00:18 I0207 07:00:18.936545  2974 containerizer.cpp:2116] Destroying container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338 in RUNNING state
> 07:00:18 I0207 07:00:18.936640  2975 linux_launcher.cpp:505] Asked to destroy container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.937069  2975 linux_launcher.cpp:548] Using freezer to destroy cgroup mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.937824  2971 cgroups.cpp:2726] Freezing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.938868  2977 cgroups.cpp:1439] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/966d71b2-cbc7-40a6-accf-bef80d3d8338 after 1.015808ms
> 07:00:18 I0207 07:00:18.939909  2975 cgroups.cpp:2744] Thawing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:18 I0207 07:00:18.940995  2977 cgroups.cpp:1468] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/966d71b2-cbc7-40a6-accf-bef80d3d8338 after 1.072128ms
> 07:00:19 I0207 07:00:18.975450  2973 containerizer.cpp:2479] Container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338 has exited
> 07:00:19 I0207 07:00:18.976356  2973 provisioner.cpp:484] Ignoring destroy request for unknown container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338
> 07:00:19 I0207 07:00:18.976426  2973 containerizer.cpp:2395] Checkpointing termination state to nested container's runtime directory '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/966d71b2-cbc7-40a6-accf-bef80d3d8338/termination'
> 07:00:19 I0207 07:00:18.977567 29023 default_executor.cpp:673] Child container 4cfc866d-9fef-442b-8682-58dabb305974.966d71b2-cbc7-40a6-accf-bef80d3d8338 of task '16b247b5-e1dd-4395-a2d7-71c9c393e5bb' in state TASK_KILLED terminated with status terminated with signal Killed
> 07:00:19 I0207 07:00:18.977624 29023 default_executor.cpp:708] Killing task group containing tasks [ 16b247b5-e1dd-4395-a2d7-71c9c393e5bb, c24a618d-ce08-4784-b8c7-0eeda79d121b ]
> 07:00:19 I0207 07:00:18.977648 29023 default_executor.cpp:813] Killing child container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:18.977864  2973 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:19 I0207 07:00:18.978206  2977 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:19 I0207 07:00:18.978425  2977 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44416
> 07:00:19 I0207 07:00:18.978505  2977 http.cpp:517] Processing call KILL_NESTED_CONTAINER
> 07:00:19 I0207 07:00:18.978755  2972 containerizer.cpp:2116] Destroying container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e in RUNNING state
> 07:00:19 I0207 07:00:18.978834  2972 linux_launcher.cpp:505] Asked to destroy container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:18.979177  2972 linux_launcher.cpp:548] Using freezer to destroy cgroup mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:18.979863  2974 cgroups.cpp:2726] Freezing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:18.980865  2973 cgroups.cpp:1439] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/486b0abc-966d-49b7-b2e0-58cd1d4b462e after 969984ns
> 07:00:19 I0207 07:00:18.981984  2971 cgroups.cpp:2744] Thawing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:18.982986  2971 cgroups.cpp:1468] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/486b0abc-966d-49b7-b2e0-58cd1d4b462e after 984832ns
> 07:00:19 I0207 07:00:19.017686  2977 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:19 I0207 07:00:19.017765  2977 slave.cpp:3771] Handling status update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.018388  2977 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.018442  2977 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:19 I0207 07:00:19.018528  2976 slave.cpp:4211] Forwarding the update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:19 I0207 07:00:19.018610  2976 slave.cpp:4105] Status update manager successfully handled status update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.018651  2977 master.cpp:6094] Status update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:19 I0207 07:00:19.018682  2977 master.cpp:6162] Forwarding status update TASK_KILLED (UUID: 5811c6a9-1fd6-48a2-90a6-65f5e9c9ee84) for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.018759  2977 master.cpp:8242] Updating the state of task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:19 I0207 07:00:19.018950  2974 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.3; mem(*)(allocated: *):96; disk(*)(allocated: *):96) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.019011 29022 default_executor.cpp:153] Received ACKNOWLEDGED event
> 07:00:19 I0207 07:00:19.019037  2973 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:19 I0207 07:00:19.076431  2977 containerizer.cpp:2479] Container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e has exited
> 07:00:19 I0207 07:00:19.077306  2974 provisioner.cpp:484] Ignoring destroy request for unknown container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e
> 07:00:19 I0207 07:00:19.077369  2974 containerizer.cpp:2395] Checkpointing termination state to nested container's runtime directory '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/486b0abc-966d-49b7-b2e0-58cd1d4b462e/termination'
> 07:00:19 I0207 07:00:19.077934 29025 default_executor.cpp:673] Child container 4cfc866d-9fef-442b-8682-58dabb305974.486b0abc-966d-49b7-b2e0-58cd1d4b462e of task 'c24a618d-ce08-4784-b8c7-0eeda79d121b' in state TASK_KILLED terminated with status terminated with signal Killed
> 07:00:19 I0207 07:00:19.078296  2974 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:19 I0207 07:00:19.117736  2970 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:19 I0207 07:00:19.117791  2970 slave.cpp:3771] Handling status update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.118321  2977 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.118379  2977 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:19 I0207 07:00:19.118453  2971 slave.cpp:4211] Forwarding the update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:19 I0207 07:00:19.118526  2971 slave.cpp:4105] Status update manager successfully handled status update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.118571  2970 master.cpp:6094] Status update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:19 I0207 07:00:19.118604  2970 master.cpp:6162] Forwarding status update TASK_KILLED (UUID: 9f6f1fc0-5838-4a46-a4c1-4543e28dc1c9) for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.118687  2970 master.cpp:8242] Updating the state of task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:19 I0207 07:00:19.118865  2976 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.2; mem(*)(allocated: *):64; disk(*)(allocated: *):64) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.118947 29019 default_executor.cpp:153] Received ACKNOWLEDGED event
> 07:00:19 I0207 07:00:19.118973  2973 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:19 I0207 07:00:19.119366  2977 scheduler.cpp:243] Sending KILL call to http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:19 I0207 07:00:19.119678  2972 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
> 07:00:19 I0207 07:00:19.157742  2970 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 10.153.254.29:44398
> 07:00:19 I0207 07:00:19.157784  2970 master.cpp:4851] Processing KILL call for task '8cd47a02-ff21-4585-ab72-0ce803a4be78' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:19 I0207 07:00:19.157804  2970 master.cpp:4924] Telling agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) to kill task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:19 I0207 07:00:19.157855  2976 slave.cpp:2383] Asked to kill task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.158260 29024 default_executor.cpp:153] Received KILL event
> 07:00:19 I0207 07:00:19.158283 29024 default_executor.cpp:841] Received kill for task '8cd47a02-ff21-4585-ab72-0ce803a4be78'
> 07:00:19 I0207 07:00:19.158294 29024 default_executor.cpp:813] Killing child container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.158717  2974 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1'
> 07:00:19 I0207 07:00:19.158946  2972 http.cpp:303] HTTP POST for /slave(716)/api/v1 from 10.153.254.29:44418
> 07:00:19 I0207 07:00:19.159024  2972 http.cpp:517] Processing call KILL_NESTED_CONTAINER
> 07:00:19 I0207 07:00:19.159195  2976 containerizer.cpp:2116] Destroying container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 in RUNNING state
> 07:00:19 I0207 07:00:19.159293  2972 linux_launcher.cpp:505] Asked to destroy container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.159647  2972 linux_launcher.cpp:548] Using freezer to destroy cgroup mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.160347  2973 cgroups.cpp:2726] Freezing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.161346  2973 cgroups.cpp:1439] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/5e04c992-3a62-4187-86b2-2ad4abb2f5e8 after 974080ns
> 07:00:19 I0207 07:00:19.162377  2972 cgroups.cpp:2744] Thawing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.163326  2975 cgroups.cpp:1468] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos/5e04c992-3a62-4187-86b2-2ad4abb2f5e8 after 929792ns
> 07:00:19 I0207 07:00:19.177110  2975 containerizer.cpp:2479] Container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 has exited
> 07:00:19 I0207 07:00:19.178030  2970 provisioner.cpp:484] Ignoring destroy request for unknown container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8
> 07:00:19 I0207 07:00:19.178082  2970 containerizer.cpp:2395] Checkpointing termination state to nested container's runtime directory '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_6mgrFO/containers/4cfc866d-9fef-442b-8682-58dabb305974/containers/5e04c992-3a62-4187-86b2-2ad4abb2f5e8/termination'
> 07:00:19 I0207 07:00:19.178556 29020 default_executor.cpp:673] Child container 4cfc866d-9fef-442b-8682-58dabb305974.5e04c992-3a62-4187-86b2-2ad4abb2f5e8 of task '8cd47a02-ff21-4585-ab72-0ce803a4be78' in state TASK_KILLED terminated with status terminated with signal Killed
> 07:00:19 I0207 07:00:19.178586 29020 default_executor.cpp:797] Terminating after 1secs
> 07:00:19 I0207 07:00:19.181659  2975 process.cpp:3704] Handling HTTP event for process 'slave(716)' with path: '/slave(716)/api/v1/executor'
> 07:00:19 I0207 07:00:19.221710  2972 http.cpp:303] HTTP POST for /slave(716)/api/v1/executor from 10.153.254.29:44402
> 07:00:19 I0207 07:00:19.221762  2972 slave.cpp:3771] Handling status update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.222328  2974 status_update_manager.cpp:323] Received status update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.222389  2974 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to the agent
> 07:00:19 I0207 07:00:19.222486  2977 slave.cpp:4211] Forwarding the update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 to master@10.153.254.29:38570
> 07:00:19 I0207 07:00:19.222591  2977 slave.cpp:4105] Status update manager successfully handled status update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.222607  2971 master.cpp:6094] Status update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 from agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:19 I0207 07:00:19.222627  2971 master.cpp:6162] Forwarding status update TASK_KILLED (UUID: c62e4ad7-468a-4934-9b77-815d878de732) for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.222712  2971 master.cpp:8242] Updating the state of task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:19 I0207 07:00:19.222867  2975 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:19 I0207 07:00:19.223067  2970 scheduler.cpp:676] Enqueuing event UPDATE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:19 I0207 07:00:19.779280  2973 hierarchical.cpp:1907] No inverse offers to send out!
> 07:00:19 I0207 07:00:19.779335  2973 hierarchical.cpp:1401] Performed allocation for 1 agents in 276292ns
> 07:00:19 I0207 07:00:19.779414  2971 master.cpp:6932] Sending 1 offers to framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:19 I0207 07:00:19.780038  2973 scheduler.cpp:676] Enqueuing event OFFERS received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:20 I0207 07:00:20.178755 29020 default_executor.cpp:708] Killing task group containing tasks [ 8cd47a02-ff21-4585-ab72-0ce803a4be78 ]
> 07:00:20 I0207 07:00:20.284885  2970 containerizer.cpp:2479] Container 4cfc866d-9fef-442b-8682-58dabb305974 has exited
> 07:00:20 I0207 07:00:20.284914  2970 containerizer.cpp:2116] Destroying container 4cfc866d-9fef-442b-8682-58dabb305974 in RUNNING state
> 07:00:20 I0207 07:00:20.285010  2975 linux_launcher.cpp:505] Asked to destroy container 4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 I0207 07:00:20.285462  2975 linux_launcher.cpp:548] Using freezer to destroy cgroup mesos/4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 I0207 07:00:20.286226  2977 cgroups.cpp:2726] Freezing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos
> 07:00:20 I0207 07:00:20.286248  2974 cgroups.cpp:2726] Freezing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 I0207 07:00:20.288529  2975 cgroups.cpp:1439] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos after 2.279168ms
> 07:00:20 I0207 07:00:20.288636  2970 cgroups.cpp:1439] Successfully froze cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974 after 2.370048ms
> 07:00:20 I0207 07:00:20.289970  2973 cgroups.cpp:2744] Thawing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos
> 07:00:20 I0207 07:00:20.290127  2975 cgroups.cpp:2744] Thawing cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 I0207 07:00:20.291142  2973 cgroups.cpp:1468] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974/mesos after 1.154048ms
> 07:00:20 I0207 07:00:20.291566  2970 cgroups.cpp:1468] Successfully thawed cgroup /sys/fs/cgroup/freezer/mesos/4cfc866d-9fef-442b-8682-58dabb305974 after 1.420032ms
> 07:00:20 I0207 07:00:20.292809  2970 provisioner.cpp:484] Ignoring destroy request for unknown container 4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 I0207 07:00:20.293262  2970 slave.cpp:4706] Executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 exited with status 0
> 07:00:20 I0207 07:00:20.293408  2970 master.cpp:6224] Executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal): exited with status 0
> 07:00:20 I0207 07:00:20.293469  2970 master.cpp:8365] Removing executor 'default' with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.293663  2971 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):1.9; mem(*)(allocated: *):992; disk(*)(allocated: *):992; ports(*)(allocated: *):[31000-32000]) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.293929  2970 scheduler.cpp:676] Enqueuing event FAILURE received from http://10.153.254.29:38570/master/api/v1/scheduler
> 07:00:20 I0207 07:00:20.294646  2973 master.cpp:1380] Framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) disconnected
> 07:00:20 I0207 07:00:20.294739  2973 master.cpp:3080] Deactivating framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:20 W0207 07:00:20.294898  2973 master.hpp:2262] Unable to send event to framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default): connection closed
> 07:00:20 I0207 07:00:20.294934  2973 master.cpp:3057] Disconnecting framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:20 I0207 07:00:20.294951  2973 master.cpp:1395] Giving framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default) 0ns to failover
> 07:00:20 I0207 07:00:20.295022  2974 hierarchical.cpp:415] Deactivated framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.295150  2974 hierarchical.cpp:1079] Recovered cpus(*)(allocated: *):1.9; mem(*)(allocated: *):992; disk(*)(allocated: *):992; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 from framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296192  2972 master.cpp:6772] Framework failover timeout, removing framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:20 I0207 07:00:20.296218  2972 master.cpp:7657] Removing framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (default)
> 07:00:20 I0207 07:00:20.296278  2972 master.cpp:8242] Updating the state of task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:20 I0207 07:00:20.296283  2974 slave.cpp:2613] Asked to shut down framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 by master@10.153.254.29:38570
> 07:00:20 I0207 07:00:20.296320  2974 slave.cpp:2638] Shutting down framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296336  2974 slave.cpp:4806] Cleaning up executor 'default' of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (via HTTP)
> 07:00:20 I0207 07:00:20.296311  2972 master.cpp:8336] Removing task c24a618d-ce08-4784-b8c7-0eeda79d121b with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.296442  2972 master.cpp:8242] Updating the state of task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:20 I0207 07:00:20.296480  2971 gc.cpp:55] Scheduling '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default/runs/4cfc866d-9fef-442b-8682-58dabb305974' for gc 6.99999659133037days in the future
> 07:00:20 I0207 07:00:20.296504  2974 slave.cpp:4894] Cleaning up framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296465  2972 master.cpp:8336] Removing task 8cd47a02-ff21-4585-ab72-0ce803a4be78 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.296556  2971 gc.cpp:55] Scheduling '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000/executors/default' for gc 6.99999659133037days in the future
> 07:00:20 I0207 07:00:20.296576  2972 master.cpp:8242] Updating the state of task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
> 07:00:20 I0207 07:00:20.296604  2975 status_update_manager.cpp:285] Closing status update streams for framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296593  2972 master.cpp:8336] Removing task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000 on agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.296638  2971 gc.cpp:55] Scheduling '/tmp/ROOT_DOCKER_DockerAndMesosContainerizers_DefaultExecutorTest_KillTask_0_2EklH4/slaves/fa1554c4-572a-4b89-8994-a89460f588d3-S0/frameworks/fa1554c4-572a-4b89-8994-a89460f588d3-0000' for gc 6.99999659133037days in the future
> 07:00:20 I0207 07:00:20.296641  2975 status_update_manager.cpp:531] Cleaning up status update stream for task c24a618d-ce08-4784-b8c7-0eeda79d121b of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296808  2976 hierarchical.cpp:362] Removed framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296821  2975 status_update_manager.cpp:531] Cleaning up status update stream for task 8cd47a02-ff21-4585-ab72-0ce803a4be78 of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 I0207 07:00:20.296928  2975 status_update_manager.cpp:531] Cleaning up status update stream for task 16b247b5-e1dd-4395-a2d7-71c9c393e5bb of framework fa1554c4-572a-4b89-8994-a89460f588d3-0000
> 07:00:20 W0207 07:00:20.297485  2976 containerizer.cpp:2104] Attempted to destroy unknown container 4cfc866d-9fef-442b-8682-58dabb305974
> 07:00:20 ../../src/tests/cluster.cpp:576: Failure
> 07:00:20 Value of: containers.get().empty()
> 07:00:20   Actual: false
> 07:00:20 Expected: true
> 07:00:20 Failed to destroy containers: { 4cfc866d-9fef-442b-8682-58dabb305974 }
> 07:00:20 I0207 07:00:20.297739  2977 slave.cpp:803] Agent terminating
> 07:00:20 I0207 07:00:20.297816  2973 master.cpp:1263] Agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal) disconnected
> 07:00:20 I0207 07:00:20.297837  2973 master.cpp:3117] Disconnecting agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.297860  2973 master.cpp:3136] Deactivating agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 at slave(716)@10.153.254.29:38570 (ip-10-153-254-29.ec2.internal)
> 07:00:20 I0207 07:00:20.297920  2977 hierarchical.cpp:617] Agent fa1554c4-572a-4b89-8994-a89460f588d3-S0 deactivated
> 07:00:20 I0207 07:00:20.300276  2952 master.cpp:1102] Master terminating
> 07:00:20 I0207 07:00:20.300390  2971 hierarchical.cpp:548] Removed agent fa1554c4-572a-4b89-8994-a89460f588d3-S0
> 07:00:20 [  FAILED  ] ROOT_DOCKER_DockerAndMesosContainerizers/DefaultExecutorTest.KillTask/0, where GetParam() = "docker,mesos" (2527 ms)
> {noformat}
> Note that the other parameterized test {{MesosContainerizer/DefaultExecutorTest.KillTask/0}} passes successfully though.



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)

Mime
View raw message