See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4669/display/redirect>
------------------------------------------ [...truncated 4.26 MB...] 3: I1223 10:33:24.011610 17616 slave.cpp:4027] Task status update manager successfully handled status update acknowledgement (UUID: efe253c0-edc9-4bf6-bf07-fcc2404945ba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.019949 18717 executor.cpp:478] Running '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>' 3: I1223 10:33:24.022881 18717 executor.cpp:651] Forked command at 18729 3: I1223 10:33:24.028774 17620 slave.cpp:4779] Handling status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 from executor(1)@172.17.0.3:38335 3: I1223 10:33:24.031376 17617 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.031549 17617 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to the agent 3: I1223 10:33:24.031808 17625 slave.cpp:5262] Forwarding the update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to [email protected]:49345 3: I1223 10:33:24.032191 17625 slave.cpp:5155] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.032271 17625 slave.cpp:5171] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to executor(1)@172.17.0.3:38335 3: I1223 10:33:24.032461 17616 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 from agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.032518 17616 master.cpp:7906] Forwarding status update TASK_RUNNING (Status UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.032708 17616 master.cpp:10153] Updating the state of task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1223 10:33:24.033130 17613 sched.cpp:1029] Scheduler::statusUpdate took 120772ns 3: I1223 10:33:24.033504 17613 master.cpp:5843] Processing ACKNOWLEDGE call 95732960-25a0-422e-a901-bc574b15efba for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (default) at [email protected]:49345 on agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 3: I1223 10:33:24.033968 17623 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.034313 17623 slave.cpp:4027] Task status update manager successfully handled status update acknowledgement (UUID: 95732960-25a0-422e-a901-bc574b15efba) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.072409 18720 checker_process.cpp:801] COMMAND health check for task '1' returned: 0 3: I1223 10:33:24.072845 18724 executor.cpp:339] Received task health update, healthy: true 3: I1223 10:33:24.077296 17614 slave.cpp:4779] Handling status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 from executor(1)@172.17.0.3:38335 3: I1223 10:33:24.079293 17613 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.079450 17613 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to the agent 3: I1223 10:33:24.079730 17615 slave.cpp:5262] Forwarding the update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to [email protected]:49345 3: I1223 10:33:24.079989 17615 slave.cpp:5155] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.080052 17615 slave.cpp:5171] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 to executor(1)@172.17.0.3:38335 3: I1223 10:33:24.080276 17623 master.cpp:7850] Status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 from agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.080339 17623 master.cpp:7906] Forwarding status update TASK_RUNNING (Status UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 in health state healthy of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.080569 17623 master.cpp:10153] Updating the state of task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1223 10:33:24.080914 17614 sched.cpp:1029] Scheduler::statusUpdate took 102137ns 3: I1223 10:33:24.081238 17609 sched.cpp:2009] Asked to stop the driver 3: I1223 10:33:24.081331 17612 master.cpp:5843] Processing ACKNOWLEDGE call 539bd703-d10e-4687-bc4c-bd4acbaa3fb8 for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (default) at [email protected]:49345 on agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 3: I1223 10:33:24.081390 17621 sched.cpp:1191] Stopping framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.081699 17619 master.cpp:9438] Processing TEARDOWN call for framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (default) at [email protected]:49345 3: I1223 10:33:24.081735 17619 master.cpp:9450] Removing framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (default) at [email protected]:49345 3: I1223 10:33:24.081745 17624 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.081763 17619 master.cpp:3328] Deactivating framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (default) at [email protected]:49345 3: I1223 10:33:24.082028 17613 slave.cpp:4027] Task status update manager successfully handled status update acknowledgement (UUID: 539bd703-d10e-4687-bc4c-bd4acbaa3fb8) for task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.082033 17619 master.cpp:10153] Updating the state of task 1 of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) 3: I1223 10:33:24.082041 17624 hierarchical.cpp:405] Deactivated framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.082186 17613 slave.cpp:3407] Asked to shut down framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 by [email protected]:49345 3: I1223 10:33:24.082216 17613 slave.cpp:3432] Shutting down framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.082257 17613 slave.cpp:6127] Shutting down executor '1' of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 at executor(1)@172.17.0.3:38335 3: I1223 10:33:24.083230 17619 master.cpp:10259] Removing task 1 with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 on agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.083462 18712 exec.cpp:445] Executor asked to shutdown 3: I1223 10:33:24.083808 17609 slave.cpp:931] Agent terminating 3: I1223 10:33:24.083822 17615 hierarchical.cpp:1192] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 from framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.083909 17609 slave.cpp:3407] Asked to shut down framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 by @0.0.0.0:0 3: W1223 10:33:24.083948 17609 slave.cpp:3428] Ignoring shutdown framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 because it is terminating 3: I1223 10:33:24.083962 18722 executor.cpp:171] Received SHUTDOWN event 3: I1223 10:33:24.084188 18722 executor.cpp:748] Shutting down 3: I1223 10:33:24.084386 18722 executor.cpp:863] Sending SIGTERM to process tree at pid 18729 3: I1223 10:33:24.084383 17613 master.cpp:1305] Agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) disconnected 3: I1223 10:33:24.084409 17613 master.cpp:3365] Disconnecting agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.084461 17613 master.cpp:3384] Deactivating agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 at slave(73)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.084486 17615 hierarchical.cpp:344] Removed framework 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000 3: I1223 10:33:24.084771 17615 hierarchical.cpp:766] Agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 deactivated 3: I1223 10:33:24.085435 17612 containerizer.cpp:2352] Destroying container 0b3a5284-7f11-4d6f-9f68-8b421211c054 in RUNNING state 3: I1223 10:33:24.085474 17612 containerizer.cpp:2966] Transitioning the state of container 0b3a5284-7f11-4d6f-9f68-8b421211c054 from RUNNING to DESTROYING 3: I1223 10:33:24.085992 17612 launcher.cpp:156] Asked to destroy container 0b3a5284-7f11-4d6f-9f68-8b421211c054 3: I1223 10:33:24.089221 18722 executor.cpp:876] Sent SIGTERM to the following process trees: 3: [ 3: --- 18729 mesos-containerizer launch --help=false --launch_info={"command":{"shell":true,"value":"sleep 120"},"environment":{"variables":[{"name":"PATH","type":"VALUE","value":"\/usr\/local\/sbin:\/usr\/local\/bin:\/usr\/sbin:\/usr\/bin:\/sbin:\/bin"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(73)@172.17.0.3:49345"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.3:49345"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/HealthCheckTest_EnvironmentSetup_CkO2QP\/slaves\/20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0\/frameworks\/20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000\/executors\/1\/runs\/0b3a5284-7f11-4d6f-9f68-8b421211c054"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiIwYjNhNTI4NC03ZjExLTRkNmYtOWY2OC04YjQyMTIxMWMwNTQiLCJlaWQiOiIxIiwiZmlkIjoiMjBlOWU5YmEtZTJiOS00ZWVhLWExOWEtMDJiNmMwZjQ0MTJmLTAwMDAifQ.wHr_e912LI2H8s9IW99iMPBueSAv8ILrEqwLNpZqHqA"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"1"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000"},{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/HealthCheckTest_EnvironmentSetup_CkO2QP\/slaves\/20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0\/frameworks\/20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-0000\/executors\/1\/runs\/0b3a5284-7f11-4d6f-9f68-8b421211c054"}]}} --unshare_namespace_mnt=false 3: ] 3: I1223 10:33:24.089238 18722 executor.cpp:880] Scheduling escalation to SIGKILL in 3secs from now 3: I1223 10:33:24.114909 17622 containerizer.cpp:2805] Container 0b3a5284-7f11-4d6f-9f68-8b421211c054 has exited 3: I1223 10:33:24.118341 17624 provisioner.cpp:598] Ignoring destroy request for unknown container 0b3a5284-7f11-4d6f-9f68-8b421211c054 3: I1223 10:33:24.129791 17609 master.cpp:1147] Master terminating 3: I1223 10:33:24.130754 17623 hierarchical.cpp:609] Removed agent 20e9e9ba-e2b9-4eea-a19a-02b6c0f4412f-S0 3: [ OK ] HealthCheckTest.EnvironmentSetup (508 ms) 3: [ RUN ] HealthCheckTest.GracePeriod 3: I1223 10:33:24.139181 17609 cluster.cpp:172] Creating default 'local' authorizer 3: I1223 10:33:24.142441 17619 master.cpp:456] Master 6860f38b-6742-4746-957a-253f5cb1d9f6 (0100957dd866) started on 172.17.0.3:49345 3: I1223 10:33:24.142462 17619 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/59Jp9c/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/59Jp9c/master" --zk_session_timeout="10secs" 3: I1223 10:33:24.142750 17619 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1223 10:33:24.142757 17619 master.cpp:513] Master only allowing authenticated agents to register 3: I1223 10:33:24.142762 17619 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1223 10:33:24.142767 17619 credentials.hpp:37] Loading credentials for authentication from '/tmp/59Jp9c/credentials' 3: I1223 10:33:24.143075 17619 master.cpp:563] Using default 'crammd5' authenticator 3: I1223 10:33:24.143229 17619 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1223 10:33:24.143407 17619 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1223 10:33:24.143563 17619 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1223 10:33:24.143713 17619 master.cpp:642] Authorization enabled 3: I1223 10:33:24.143929 17616 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1223 10:33:24.144031 17611 whitelist_watcher.cpp:77] No whitelist given 3: I1223 10:33:24.147186 17614 master.cpp:2210] Elected as the leading master! 3: I1223 10:33:24.147214 17614 master.cpp:1689] Recovering from registrar 3: I1223 10:33:24.147382 17612 registrar.cpp:347] Recovering registrar 3: I1223 10:33:24.148128 17612 registrar.cpp:391] Successfully fetched the registry (0B) in 709120ns 3: I1223 10:33:24.148247 17612 registrar.cpp:495] Applied 1 operations in 36201ns; attempting to update the registry 3: I1223 10:33:24.148846 17616 registrar.cpp:552] Successfully updated the registry in 545024ns 3: I1223 10:33:24.148996 17616 registrar.cpp:424] Successfully recovered registrar 3: I1223 10:33:24.149510 17624 master.cpp:1803] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1223 10:33:24.149547 17617 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: W1223 10:33:24.154755 17609 process.cpp:2756] Attempted to spawn already running process [email protected]:49345 3: I1223 10:33:24.155925 17609 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1223 10:33:24.156525 17609 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1223 10:33:24.156671 17609 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1223 10:33:24.156711 17609 provisioner.cpp:299] Using default backend 'copy' 3: I1223 10:33:24.159075 17609 cluster.cpp:460] Creating default 'local' authorizer 3: I1223 10:33:24.161612 17611 slave.cpp:262] Mesos agent started on (74)@172.17.0.3:49345 3: W1223 10:33:24.161955 17609 process.cpp:2756] Attempted to spawn already running process [email protected]:49345 3: I1223 10:33:24.161630 17611 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/HealthCheckTest_GracePeriod_XQJUVl/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/HealthCheckTest_GracePeriod_XQJUVl/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/HealthCheckTest_GracePeriod_XQJUVl/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/HealthCheckTest_GracePeriod_XQJUVl/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/HealthCheckTest_GracePeriod_XQJUVl/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/HealthCheckTest_GracePeriod_XQJUVl/jwt_secret_key" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/HealthCheckTest_GracePeriod_XQJUVl" --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/HealthCheckTest_GracePeriod_yZHExh" --zk_session_timeout="10secs" 3: I1223 10:33:24.162011 17611 credentials.hpp:86] Loading credential for authentication from '/tmp/HealthCheckTest_GracePeriod_XQJUVl/credential' 3: I1223 10:33:24.162220 17611 slave.cpp:295] Agent using credential for: test-principal 3: I1223 10:33:24.162240 17611 credentials.hpp:37] Loading credentials for authentication from '/tmp/HealthCheckTest_GracePeriod_XQJUVl/http_credentials' 3: I1223 10:33:24.162529 17611 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1223 10:33:24.162652 17611 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1223 10:33:24.162839 17609 sched.cpp:232] Version: 1.5.0 3: I1223 10:33:24.162859 17611 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1223 10:33:24.162945 17611 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1223 10:33:24.163110 17611 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1223 10:33:24.163213 17611 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1223 10:33:24.163405 17614 sched.cpp:336] New master detected at [email protected]:49345 3: I1223 10:33:24.163499 17614 sched.cpp:396] Authenticating with master [email protected]:49345 3: I1223 10:33:24.163512 17614 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1223 10:33:24.163779 17621 authenticatee.cpp:121] Creating new client SASL connection 3: I1223 10:33:24.164062 17620 master.cpp:8928] Authenticating [email protected]:49345 3: I1223 10:33:24.164194 17612 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(162)@172.17.0.3:49345 3: I1223 10:33:24.164492 17615 authenticator.cpp:98] Creating new server SASL connection 3: I1223 10:33:24.164815 17617 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1223 10:33:24.164844 17617 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1223 10:33:24.164986 17617 authenticator.cpp:204] Received SASL authentication start 3: I1223 10:33:24.165056 17617 authenticator.cpp:326] Authentication requires more steps 3: I1223 10:33:24.165216 17619 authenticatee.cpp:259] Received SASL authentication step 3: I1223 10:33:24.165395 17616 authenticator.cpp:232] Received SASL authentication step 3: I1223 10:33:24.165436 17616 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0100957dd866' server FQDN: '0100957dd866' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1223 10:33:24.165453 17616 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1223 10:33:24.165103 17611 slave.cpp:612] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1223 10:33:24.165504 17611 slave.cpp:620] Agent attributes: [ ] 3: I1223 10:33:24.165506 17616 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1223 10:33:24.165524 17611 slave.cpp:629] Agent hostname: 0100957dd866 3: I1223 10:33:24.165570 17616 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0100957dd866' server FQDN: '0100957dd866' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1223 10:33:24.165585 17616 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1223 10:33:24.165596 17616 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1223 10:33:24.165608 17616 authenticator.cpp:318] Authentication success 3: I1223 10:33:24.165740 17625 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1223 10:33:24.165827 17618 authenticatee.cpp:299] Authentication success 3: I1223 10:33:24.165912 17613 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(162)@172.17.0.3:49345 3: I1223 10:33:24.165943 17625 master.cpp:8958] Successfully authenticated principal 'test-principal' at [email protected]:49345 3: I1223 10:33:24.166163 17623 sched.cpp:502] Successfully authenticated with master [email protected]:49345 3: I1223 10:33:24.166182 17623 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:49345 3: I1223 10:33:24.166311 17623 sched.cpp:857] Will retry registration in 563.401398ms if necessary 3: I1223 10:33:24.166601 17615 master.cpp:2958] Received SUBSCRIBE call for framework 'default' at [email protected]:49345 3: I1223 10:33:24.166733 17615 master.cpp:2275] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1223 10:33:24.167345 17616 state.cpp:66] Recovering state from '/tmp/HealthCheckTest_GracePeriod_yZHExh/meta' 3: I1223 10:33:24.167336 17624 master.cpp:3038] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1223 10:33:24.167551 17624 master.cpp:9149] Adding framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (default) at [email protected]:49345 with roles { } suppressed 3: I1223 10:33:24.167676 17616 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1223 10:33:24.167930 17620 containerizer.cpp:674] Recovering containerizer 3: I1223 10:33:24.168035 17623 sched.cpp:751] Framework registered with 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.168076 17623 sched.cpp:765] Scheduler::registered took 19995ns 3: I1223 10:33:24.168332 17610 hierarchical.cpp:297] Added framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.168758 17610 hierarchical.cpp:1517] Performed allocation for 0 agents in 93498ns 3: I1223 10:33:24.169469 17625 provisioner.cpp:495] Provisioner recovery complete 3: I1223 10:33:24.169816 17612 slave.cpp:6773] Finished recovery 3: I1223 10:33:24.170573 17611 slave.cpp:1055] New master detected at [email protected]:49345 3: I1223 10:33:24.170627 17615 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1223 10:33:24.170647 17611 slave.cpp:1110] Detecting new master 3: I1223 10:33:24.174932 17618 slave.cpp:1137] Authenticating with master [email protected]:49345 3: I1223 10:33:24.174991 17618 slave.cpp:1146] Using default CRAM-MD5 authenticatee 3: I1223 10:33:24.175176 17620 authenticatee.cpp:121] Creating new client SASL connection 3: I1223 10:33:24.175400 17620 master.cpp:8928] Authenticating slave(74)@172.17.0.3:49345 3: I1223 10:33:24.175494 17614 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(163)@172.17.0.3:49345 3: I1223 10:33:24.175688 17616 authenticator.cpp:98] Creating new server SASL connection 3: I1223 10:33:24.176005 17610 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1223 10:33:24.176038 17610 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1223 10:33:24.176170 17610 authenticator.cpp:204] Received SASL authentication start 3: I1223 10:33:24.176234 17610 authenticator.cpp:326] Authentication requires more steps 3: I1223 10:33:24.176336 17623 authenticatee.cpp:259] Received SASL authentication step 3: I1223 10:33:24.176441 17625 authenticator.cpp:232] Received SASL authentication step 3: I1223 10:33:24.176470 17625 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0100957dd866' server FQDN: '0100957dd866' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1223 10:33:24.176478 17625 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1223 10:33:24.176501 17625 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1223 10:33:24.176514 17625 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0100957dd866' server FQDN: '0100957dd866' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1223 10:33:24.176522 17625 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1223 10:33:24.176525 17625 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1223 10:33:24.176535 17625 authenticator.cpp:318] Authentication success 3: I1223 10:33:24.176607 17617 authenticatee.cpp:299] Authentication success 3: I1223 10:33:24.176662 17612 master.cpp:8958] Successfully authenticated principal 'test-principal' at slave(74)@172.17.0.3:49345 3: I1223 10:33:24.176708 17619 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(163)@172.17.0.3:49345 3: I1223 10:33:24.176869 17625 slave.cpp:1229] Successfully authenticated with master [email protected]:49345 3: I1223 10:33:24.177048 17625 slave.cpp:1709] Will retry registration in 6.817733ms if necessary 3: I1223 10:33:24.177304 17613 master.cpp:6078] Received register agent message from slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.177405 17613 master.cpp:3867] Authorizing agent with principal 'test-principal' 3: I1223 10:33:24.177803 17614 master.cpp:6148] Authorized registration of agent at slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.177880 17614 master.cpp:6241] Registering agent at slave(74)@172.17.0.3:49345 (0100957dd866) with id 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 3: I1223 10:33:24.178380 17616 registrar.cpp:495] Applied 1 operations in 84775ns; attempting to update the registry 3: I1223 10:33:24.178889 17623 registrar.cpp:552] Successfully updated the registry in 457984ns 3: I1223 10:33:24.179033 17612 master.cpp:6289] Admitted agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.179652 17612 master.cpp:6338] Registered agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1223 10:33:24.179708 17611 slave.cpp:1275] Registered with master [email protected]:49345; given agent ID 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 3: I1223 10:33:24.179824 17619 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1223 10:33:24.180038 17611 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/HealthCheckTest_GracePeriod_yZHExh/meta/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/slave.info' 3: I1223 10:33:24.180191 17615 hierarchical.cpp:574] Added agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 (0100957dd866) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1223 10:33:24.180496 17611 slave.cpp:1352] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"G3fKxUnKTOeM7NYSR8AtZQ=="},"slave_id":{"value":"6860f38b-6742-4746-957a-253f5cb1d9f6-S0"},"update_oversubscribed_resources":true} 3: I1223 10:33:24.180712 17613 master.cpp:7268] Received update of agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) with total oversubscribed resources {} 3: I1223 10:33:24.181038 17613 master.cpp:7362] Ignoring update on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) as it reports no changes 3: I1223 10:33:24.182050 17615 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.595517ms 3: I1223 10:33:24.182502 17620 master.cpp:8758] Sending 1 offers to framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (default) at [email protected]:49345 3: I1223 10:33:24.182938 17614 sched.cpp:921] Scheduler::resourceOffers took 89838ns 3: I1223 10:33:24.184639 17618 master.cpp:10671] Removing offer 6860f38b-6742-4746-957a-253f5cb1d9f6-O0 3: I1223 10:33:24.184768 17618 master.cpp:4274] Processing ACCEPT call for offers: [ 6860f38b-6742-4746-957a-253f5cb1d9f6-O0 ] on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) for framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (default) at [email protected]:49345 3: I1223 10:33:24.184857 17618 master.cpp:3594] Authorizing framework principal 'test-principal' to launch task 1 3: I1223 10:33:24.186651 17625 master.cpp:11426] Adding task 1 with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.186982 17625 master.cpp:5025] Launching task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (default) at [email protected]:49345 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.188261 17613 slave.cpp:1832] Got assigned task '1' for framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.189085 17613 slave.cpp:2102] Authorizing task '1' for framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.189158 17613 slave.cpp:7890] Authorizing framework principal 'test-principal' to launch task 1 3: I1223 10:33:24.190214 17617 slave.cpp:2495] Launching task '1' for framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.190685 17617 paths.cpp:756] Trying to chown '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' to user 'mesos' 3: I1223 10:33:24.190922 17617 slave.cpp:8364] Launching executor '1' of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' 3: I1223 10:33:24.191916 17617 slave.cpp:2581] Queued task '1' for executor '1' of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.192031 17617 slave.cpp:1006] Successfully attached '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' to virtual path '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/latest' 3: I1223 10:33:24.192088 17617 slave.cpp:1006] Successfully attached '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' to virtual path '/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/latest' 3: I1223 10:33:24.192490 17617 slave.cpp:3055] Launching container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 for executor '1' of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.193200 17617 slave.cpp:1006] Successfully attached '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' to virtual path '/tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539' 3: I1223 10:33:24.193681 17621 containerizer.cpp:1207] Starting container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 3: I1223 10:33:24.194649 17621 containerizer.cpp:1380] Checkpointed ContainerConfig at '/tmp/HealthCheckTest_GracePeriod_XQJUVl/containers/9fc8ebb6-4f3c-4069-80b5-4926f0edb539/config' 3: I1223 10:33:24.194684 17621 containerizer.cpp:2966] Transitioning the state of container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 from PROVISIONING to PREPARING 3: I1223 10:33:24.200438 17615 containerizer.cpp:1856] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.3:49345"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/HealthCheckTest_GracePeriod_yZHExh\/slaves\/6860f38b-6742-4746-957a-253f5cb1d9f6-S0\/frameworks\/6860f38b-6742-4746-957a-253f5cb1d9f6-0000\/executors\/1\/runs\/9fc8ebb6-4f3c-4069-80b5-4926f0edb539"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiI5ZmM4ZWJiNi00ZjNjLTQwNjktODBiNS00OTI2ZjBlZGI1MzkiLCJlaWQiOiIxIiwiZmlkIjoiNjg2MGYzOGItNjc0Mi00NzQ2LTk1N2EtMjUzZjVjYjFkOWY2LTAwMDAifQ.QOrPMsEriyzUylUsV5dNoJpPpxp_TRofCkXRov1AR3c"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"1"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"6860f38b-6742-4746-957a-253f5cb1d9f6-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"6860f38b-6742-4746-957a-253f5cb1d9f6-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(74)@172.17.0.3:49345"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/HealthCheckTest_GracePeriod_yZHExh\/slaves\/6860f38b-6742-4746-957a-253f5cb1d9f6-S0\/frameworks\/6860f38b-6742-4746-957a-253f5cb1d9f6-0000\/executors\/1\/runs\/9fc8ebb6-4f3c-4069-80b5-4926f0edb539"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/HealthCheckTest_GracePeriod_yZHExh\/slaves\/6860f38b-6742-4746-957a-253f5cb1d9f6-S0\/frameworks\/6860f38b-6742-4746-957a-253f5cb1d9f6-0000\/executors\/1\/runs\/9fc8ebb6-4f3c-4069-80b5-4926f0edb539"}" --pipe_read="8" --pipe_write="9" --runtime_directory="/tmp/HealthCheckTest_GracePeriod_XQJUVl/containers/9fc8ebb6-4f3c-4069-80b5-4926f0edb539" --unshare_namespace_mnt="false"' 3: I1223 10:33:24.202718 17615 launcher.cpp:140] Forked child with pid '18732' for container '9fc8ebb6-4f3c-4069-80b5-4926f0edb539' 3: I1223 10:33:24.203362 17615 containerizer.cpp:2966] Transitioning the state of container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 from PREPARING to ISOLATING 3: I1223 10:33:24.204967 17614 containerizer.cpp:2966] Transitioning the state of container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 from ISOLATING to FETCHING 3: I1223 10:33:24.205178 17610 fetcher.cpp:369] Starting to fetch URIs for container: 9fc8ebb6-4f3c-4069-80b5-4926f0edb539, directory: /tmp/HealthCheckTest_GracePeriod_yZHExh/slaves/6860f38b-6742-4746-957a-253f5cb1d9f6-S0/frameworks/6860f38b-6742-4746-957a-253f5cb1d9f6-0000/executors/1/runs/9fc8ebb6-4f3c-4069-80b5-4926f0edb539 3: I1223 10:33:24.206459 17624 containerizer.cpp:2966] Transitioning the state of container 9fc8ebb6-4f3c-4069-80b5-4926f0edb539 from FETCHING to RUNNING 3: I1223 10:33:24.456656 18743 exec.cpp:162] Version: 1.5.0 3: I1223 10:33:24.470919 17622 slave.cpp:4322] Got registration for executor '1' of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 from executor(1)@172.17.0.3:41671 3: I1223 10:33:24.474864 17623 slave.cpp:2786] Sending queued task '1' to executor '1' of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 at executor(1)@172.17.0.3:41671 3: I1223 10:33:24.477483 18738 exec.cpp:236] Executor registered on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 3: I1223 10:33:24.482352 18745 executor.cpp:171] Received SUBSCRIBED event 3: I1223 10:33:24.483466 18745 executor.cpp:175] Subscribed executor on 0100957dd866 3: I1223 10:33:24.483685 18745 executor.cpp:171] Received LAUNCH event 3: I1223 10:33:24.485599 18745 executor.cpp:638] Starting task 1 3: I1223 10:33:24.489833 17611 slave.cpp:4779] Handling status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 from executor(1)@172.17.0.3:41671 3: I1223 10:33:24.492192 17621 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.492246 17621 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.493002 17621 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 to the agent 3: I1223 10:33:24.493311 17619 slave.cpp:5262] Forwarding the update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 to [email protected]:49345 3: I1223 10:33:24.493599 17619 slave.cpp:5155] Task status update manager successfully handled status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.493662 17619 slave.cpp:5171] Sending acknowledgement for status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 to executor(1)@172.17.0.3:41671 3: I1223 10:33:24.493803 17623 master.cpp:7850] Status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 from agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 at slave(74)@172.17.0.3:49345 (0100957dd866) 3: I1223 10:33:24.493871 17623 master.cpp:7906] Forwarding status update TASK_STARTING (Status UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.494091 17623 master.cpp:10153] Updating the state of task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING) 3: I1223 10:33:24.494328 17618 sched.cpp:1029] Scheduler::statusUpdate took 81848ns 3: I1223 10:33:24.494621 17613 master.cpp:5843] Processing ACKNOWLEDGE call e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3 for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 (default) at [email protected]:49345 on agent 6860f38b-6742-4746-957a-253f5cb1d9f6-S0 3: I1223 10:33:24.494954 17612 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.495208 17625 slave.cpp:4027] Task status update manager successfully handled status update acknowledgement (UUID: e1d47cc4-cf17-478e-bfd1-62cbdcdbefb3) for task 1 of framework 6860f38b-6742-4746-957a-253f5cb1d9f6-0000 3: I1223 10:33:24.501514 18745 executor.cpp:478] Running '/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>' Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
