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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4486/display/redirect?page=changes>
Changes:
[alexr] Fixed a task status update race in default executor tests.
[alexr] Renamed `TaskStatusEq()` to `TaskStatusTaskIdEq()`.
------------------------------------------
[...truncated 4.04 MB...]
3: I1121 18:25:08.877876 21295 executor.cpp:747] Shutting down
3: I1121 18:25:08.877984 21295 executor.cpp:854] Sending SIGTERM to process
tree at pid 21297
3: I1121 18:25:08.878185 20005 launcher.cpp:156] Asked to destroy container
df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd
3: I1121 18:25:08.878530 19992 hierarchical.cpp:358] Removed framework
693fcdf0-77ff-4738-bed3-9a50de515b75-0000
3: I1121 18:25:08.881494 21295 executor.cpp:867] Sent SIGTERM to the following
process trees:
3: [
3: --- 21297 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(66)@172.17.0.3:39546"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"693fcdf0-77ff-4738-bed3-9a50de515b75-S0"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.3:39546"},{"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_HealthyTask_86Dzd2\/slaves\/693fcdf0-77ff-4738-bed3-9a50de515b75-S0\/frameworks\/693fcdf0-77ff-4738-bed3-9a50de515b75-0000\/executors\/1\/runs\/df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiJkZjQ5YTdlYi0xMjBhLTRiMGYtYTJjNy1kNzM4NmM4Y2E5ZGQiLCJlaWQiOiIxIiwiZmlkIjoiNjkzZmNkZjAtNzdmZi00NzM4LWJlZDMtOWE1MGRlNTE1Yjc1LTAwMDAifQ.LxdTMQMi38rx89SPyrz2vKhc1gSH24LmiRCk_1i4UAg"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"1"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"693fcdf0-77ff-4738-bed3-9a50de515b75-0000"},{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/HealthCheckTest_HealthyTask_86Dzd2\/slaves\/693fcdf0-77ff-4738-bed3-9a50de515b75-S0\/frameworks\/693fcdf0-77ff-4738-bed3-9a50de515b75-0000\/executors\/1\/runs\/df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd"}]}}
--unshare_namespace_mnt=false
3: ]
3: I1121 18:25:08.881508 21295 executor.cpp:871] Scheduling escalation to
SIGKILL in 3secs from now
3: I1121 18:25:08.904248 19998 slave.cpp:5132] Got exited event for
executor(1)@172.17.0.3:33694
3: I1121 18:25:08.965750 19989 containerizer.cpp:2773] Container
df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd has exited
3: I1121 18:25:08.968840 20009 provisioner.cpp:557] Ignoring destroy request
for unknown container df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd
3: I1121 18:25:08.970172 20003 slave.cpp:5541] Executor '1' of framework
693fcdf0-77ff-4738-bed3-9a50de515b75-0000 terminated with signal Killed
3: I1121 18:25:08.970247 20003 slave.cpp:5645] Cleaning up executor '1' of
framework 693fcdf0-77ff-4738-bed3-9a50de515b75-0000 at
executor(1)@172.17.0.3:33694
3: I1121 18:25:08.970700 20002 gc.cpp:90] Scheduling
'/tmp/HealthCheckTest_HealthyTask_86Dzd2/slaves/693fcdf0-77ff-4738-bed3-9a50de515b75-S0/frameworks/693fcdf0-77ff-4738-bed3-9a50de515b75-0000/executors/1/runs/df49a7eb-120a-4b0f-a2c7-d7386c8ca9dd'
for gc 6.99998876729185days in the future
3: I1121 18:25:08.970837 20003 slave.cpp:5752] Cleaning up framework
693fcdf0-77ff-4738-bed3-9a50de515b75-0000
3: I1121 18:25:08.970890 20002 gc.cpp:90] Scheduling
'/tmp/HealthCheckTest_HealthyTask_86Dzd2/slaves/693fcdf0-77ff-4738-bed3-9a50de515b75-S0/frameworks/693fcdf0-77ff-4738-bed3-9a50de515b75-0000/executors/1'
for gc 6.99998876423704days in the future
3: I1121 18:25:08.970942 20006 task_status_update_manager.cpp:289] Closing task
status update streams for framework 693fcdf0-77ff-4738-bed3-9a50de515b75-0000
3: I1121 18:25:08.970996 20006 task_status_update_manager.cpp:538] Cleaning up
status update stream for task 1 of framework
693fcdf0-77ff-4738-bed3-9a50de515b75-0000
3: I1121 18:25:08.971019 20002 gc.cpp:90] Scheduling
'/tmp/HealthCheckTest_HealthyTask_86Dzd2/slaves/693fcdf0-77ff-4738-bed3-9a50de515b75-S0/frameworks/693fcdf0-77ff-4738-bed3-9a50de515b75-0000'
for gc 6.99998876232593days in the future
3: I1121 18:25:08.971325 19986 slave.cpp:883] Agent terminating
3: I1121 18:25:08.971712 19993 master.cpp:1311] Agent
693fcdf0-77ff-4738-bed3-9a50de515b75-S0 at slave(66)@172.17.0.3:39546
(f11955b92810) disconnected
3: I1121 18:25:08.971774 19993 master.cpp:3370] Disconnecting agent
693fcdf0-77ff-4738-bed3-9a50de515b75-S0 at slave(66)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:08.971882 19993 master.cpp:3389] Deactivating agent
693fcdf0-77ff-4738-bed3-9a50de515b75-S0 at slave(66)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:08.972018 20009 hierarchical.cpp:697] Agent
693fcdf0-77ff-4738-bed3-9a50de515b75-S0 deactivated
3: I1121 18:25:08.981770 19986 master.cpp:1153] Master terminating
3: I1121 18:25:08.982517 20001 hierarchical.cpp:633] Removed agent
693fcdf0-77ff-4738-bed3-9a50de515b75-S0
3: [ OK ] HealthCheckTest.HealthyTask (593 ms)
3: [ RUN ] HealthCheckTest.HealthyTaskNonShell
3: I1121 18:25:08.991288 19986 cluster.cpp:162] Creating default 'local'
authorizer
3: I1121 18:25:08.995082 19991 master.cpp:448] Master
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6 (f11955b92810) started on 172.17.0.3:39546
3: I1121 18:25:08.995107 19991 master.cpp:450] 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/3U5zaO/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/3U5zaO/master"
--zk_session_timeout="10secs"
3: I1121 18:25:08.995460 19991 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1121 18:25:08.995467 19991 master.cpp:505] Master only allowing
authenticated agents to register
3: I1121 18:25:08.995471 19991 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1121 18:25:08.995488 19991 credentials.hpp:37] Loading credentials for
authentication from '/tmp/3U5zaO/credentials'
3: I1121 18:25:08.995892 19991 master.cpp:555] Using default 'crammd5'
authenticator
3: I1121 18:25:08.996107 19991 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1121 18:25:08.996314 19991 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1121 18:25:08.996465 19991 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1121 18:25:08.996624 19991 master.cpp:634] Authorization enabled
3: I1121 18:25:08.996805 20003 whitelist_watcher.cpp:77] No whitelist given
3: I1121 18:25:08.996840 19997 hierarchical.cpp:173] Initialized hierarchical
allocator process
3: I1121 18:25:09.000547 19995 master.cpp:2215] Elected as the leading master!
3: I1121 18:25:09.000594 19995 master.cpp:1695] Recovering from registrar
3: I1121 18:25:09.000828 20004 registrar.cpp:347] Recovering registrar
3: I1121 18:25:09.001762 20004 registrar.cpp:391] Successfully fetched the
registry (0B) in 878080ns
3: I1121 18:25:09.001907 20004 registrar.cpp:495] Applied 1 operations in
48799ns; attempting to update the registry
3: I1121 18:25:09.002720 20004 registrar.cpp:552] Successfully updated the
registry in 729088ns
3: I1121 18:25:09.002919 20004 registrar.cpp:424] Successfully recovered
registrar
3: I1121 18:25:09.003361 20010 master.cpp:1808] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1121 18:25:09.003465 19994 hierarchical.cpp:211] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1121 18:25:09.009171 19986 process.cpp:2756] Attempted to spawn already
running process [email protected]:39546
3: I1121 18:25:09.010460 19986 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1121 18:25:09.011116 19986 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1121 18:25:09.011251 19986 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1121 18:25:09.011288 19986 provisioner.cpp:259] Using default backend 'copy'
3: I1121 18:25:09.013474 19986 cluster.cpp:448] Creating default 'local'
authorizer
3: I1121 18:25:09.016057 19989 slave.cpp:262] Mesos agent started on
(67)@172.17.0.3:39546
3: W1121 18:25:09.016731 19986 process.cpp:2756] Attempted to spawn already
running process [email protected]:39546
3: I1121 18:25:09.016078 19989 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/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_HealthyTaskNonShell_WDxsNu/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_HealthyTaskNonShell_WDxsNu/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/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_HealthyTaskNonShell_WDxsNu/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu"
--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_HealthyTaskNonShell_Xaz0tE"
--zk_session_timeout="10secs"
3: I1121 18:25:09.016835 19989 credentials.hpp:86] Loading credential for
authentication from '/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/credential'
3: I1121 18:25:09.017168 19989 slave.cpp:295] Agent using credential for:
test-principal
3: I1121 18:25:09.017195 19989 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/http_credentials'
3: I1121 18:25:09.017668 19989 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1121 18:25:09.017880 19989 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1121 18:25:09.018040 19986 sched.cpp:232] Version: 1.5.0
3: I1121 18:25:09.018210 19989 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1121 18:25:09.018368 19989 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1121 18:25:09.018637 19989 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1121 18:25:09.018750 19989 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1121 18:25:09.019111 20007 sched.cpp:336] New master detected at
[email protected]:39546
3: I1121 18:25:09.019242 20007 sched.cpp:396] Authenticating with master
[email protected]:39546
3: I1121 18:25:09.019258 20007 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1121 18:25:09.019615 20006 authenticatee.cpp:121] Creating new client SASL
connection
3: I1121 18:25:09.020007 19990 master.cpp:8306] Authenticating
[email protected]:39546
3: I1121 18:25:09.020148 19988 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(153)@172.17.0.3:39546
3: I1121 18:25:09.020437 19997 authenticator.cpp:98] Creating new server SASL
connection
3: I1121 18:25:09.020722 20005 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1121 18:25:09.020754 20005 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1121 18:25:09.020918 19999 authenticator.cpp:204] Received SASL
authentication start
3: I1121 18:25:09.020993 19999 authenticator.cpp:326] Authentication requires
more steps
3: I1121 18:25:09.020779 19989 slave.cpp:593] 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: I1121 18:25:09.021073 19989 slave.cpp:601] Agent attributes: [ ]
3: I1121 18:25:09.021085 19989 slave.cpp:610] Agent hostname: f11955b92810
3: I1121 18:25:09.021157 19999 authenticatee.cpp:259] Received SASL
authentication step
3: I1121 18:25:09.021348 19995 task_status_update_manager.cpp:181] Pausing
sending task status updates
3: I1121 18:25:09.021479 20000 authenticator.cpp:232] Received SASL
authentication step
3: I1121 18:25:09.021528 20000 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'f11955b92810' server FQDN: 'f11955b92810'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1121 18:25:09.021548 20000 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1121 18:25:09.021580 20000 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1121 18:25:09.021600 20000 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'f11955b92810' server FQDN: 'f11955b92810'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1121 18:25:09.021606 20000 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1121 18:25:09.021611 20000 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1121 18:25:09.021627 20000 authenticator.cpp:318] Authentication success
3: I1121 18:25:09.021749 19987 authenticatee.cpp:299] Authentication success
3: I1121 18:25:09.021821 19992 master.cpp:8336] Successfully authenticated
principal 'test-principal' at
[email protected]:39546
3: I1121 18:25:09.021903 19998 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(153)@172.17.0.3:39546
3: I1121 18:25:09.022068 20000 sched.cpp:502] Successfully authenticated with
master [email protected]:39546
3: I1121 18:25:09.022090 20000 sched.cpp:824] Sending SUBSCRIBE call to
[email protected]:39546
3: I1121 18:25:09.022204 20000 sched.cpp:857] Will retry registration in
1.759925332secs if necessary
3: I1121 18:25:09.022475 19991 master.cpp:2963] Received SUBSCRIBE call for
framework 'default' at
[email protected]:39546
3: I1121 18:25:09.022595 19991 master.cpp:2280] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
3: I1121 18:25:09.023176 19993 master.cpp:3043] Subscribing framework default
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1121 18:25:09.023334 19994 state.cpp:64] Recovering state from
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/meta'
3: I1121 18:25:09.023668 19994 task_status_update_manager.cpp:207] Recovering
task status update manager
3: I1121 18:25:09.023896 20002 sched.cpp:751] Framework registered with
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.023906 19987 containerizer.cpp:668] Recovering containerizer
3: I1121 18:25:09.023962 20002 sched.cpp:765] Scheduler::registered took 32475ns
3: I1121 18:25:09.024032 20006 hierarchical.cpp:306] Added framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.024349 20006 hierarchical.cpp:1457] Performed allocation for
0 agents in 120798ns
3: I1121 18:25:09.025557 19996 provisioner.cpp:455] Provisioner recovery
complete
3: I1121 18:25:09.025976 20005 slave.cpp:6443] Finished recovery
3: I1121 18:25:09.026798 19997 task_status_update_manager.cpp:181] Pausing
sending task status updates
3: I1121 18:25:09.026793 19988 slave.cpp:1007] New master detected at
[email protected]:39546
3: I1121 18:25:09.026911 19988 slave.cpp:1042] Detecting new master
3: I1121 18:25:09.034979 19995 slave.cpp:1069] Authenticating with master
[email protected]:39546
3: I1121 18:25:09.035101 19995 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1121 18:25:09.035392 20002 authenticatee.cpp:121] Creating new client SASL
connection
3: I1121 18:25:09.035763 20002 master.cpp:8306] Authenticating
slave(67)@172.17.0.3:39546
3: I1121 18:25:09.035892 20006 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(154)@172.17.0.3:39546
3: I1121 18:25:09.036319 20008 authenticator.cpp:98] Creating new server SASL
connection
3: I1121 18:25:09.036767 19998 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1121 18:25:09.036815 19998 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1121 18:25:09.036967 20004 authenticator.cpp:204] Received SASL
authentication start
3: I1121 18:25:09.037030 20004 authenticator.cpp:326] Authentication requires
more steps
3: I1121 18:25:09.037134 20004 authenticatee.cpp:259] Received SASL
authentication step
3: I1121 18:25:09.037237 20004 authenticator.cpp:232] Received SASL
authentication step
3: I1121 18:25:09.037259 20004 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'f11955b92810' server FQDN: 'f11955b92810'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1121 18:25:09.037267 20004 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1121 18:25:09.037291 20004 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1121 18:25:09.037304 20004 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'f11955b92810' server FQDN: 'f11955b92810'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1121 18:25:09.037310 20004 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1121 18:25:09.037315 20004 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1121 18:25:09.037326 20004 authenticator.cpp:318] Authentication success
3: I1121 18:25:09.037446 20009 authenticatee.cpp:299] Authentication success
3: I1121 18:25:09.037508 19987 master.cpp:8336] Successfully authenticated
principal 'test-principal' at slave(67)@172.17.0.3:39546
3: I1121 18:25:09.037569 20003 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(154)@172.17.0.3:39546
3: I1121 18:25:09.037703 20004 slave.cpp:1161] Successfully authenticated with
master [email protected]:39546
3: I1121 18:25:09.037950 20004 slave.cpp:1682] Will retry registration in
1.213434ms if necessary
3: I1121 18:25:09.038229 19989 master.cpp:6036] Received register agent message
from slave(67)@172.17.0.3:39546 (f11955b92810)
3: I1121 18:25:09.038374 19989 master.cpp:3872] Authorizing agent with
principal 'test-principal'
3: I1121 18:25:09.038890 19990 master.cpp:6098] Authorized registration of
agent at slave(67)@172.17.0.3:39546 (f11955b92810)
3: I1121 18:25:09.039028 19990 master.cpp:6191] Registering agent at
slave(67)@172.17.0.3:39546 (f11955b92810) with id
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.039505 19994 registrar.cpp:495] Applied 1 operations in
64784ns; attempting to update the registry
3: I1121 18:25:09.040113 19995 registrar.cpp:552] Successfully updated the
registry in 544us
3: I1121 18:25:09.040447 20002 master.cpp:6240] Admitted agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.041342 20008 slave.cpp:1207] Registered with master
[email protected]:39546; given agent ID 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.041200 20002 master.cpp:6273] Registered agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810) with
[{"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: I1121 18:25:09.041503 19992 task_status_update_manager.cpp:188] Resuming
sending task status updates
3: I1121 18:25:09.041579 19998 hierarchical.cpp:600] Added agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 (f11955b92810) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1121 18:25:09.041723 20008 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/meta/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/slave.info'
3: I1121 18:25:09.042208 20008 slave.cpp:1295] Forwarding total oversubscribed
resources {}
3: I1121 18:25:09.042445 20008 master.cpp:7085] Received update of agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810) with total oversubscribed resources {}
3: I1121 18:25:09.042662 20008 master.cpp:7103] Ignoring update on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810) as it reports no changes
3: I1121 18:25:09.043483 19998 hierarchical.cpp:1457] Performed allocation for
1 agents in 1.674697ms
3: I1121 18:25:09.044261 20000 master.cpp:8136] Sending 1 offers to framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546
3: I1121 18:25:09.044873 20007 sched.cpp:921] Scheduler::resourceOffers took
114424ns
3: I1121 18:25:09.047029 20005 master.cpp:10045] Removing offer
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-O0
3: I1121 18:25:09.047237 20005 master.cpp:4230] Processing ACCEPT call for
offers: [ 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-O0 ] on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810) for framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
(default) at [email protected]:39546
3: I1121 18:25:09.047365 20005 master.cpp:3599] Authorizing framework principal
'test-principal' to launch task 1
3: I1121 18:25:09.050680 19994 master.cpp:10793] Adding task 1 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 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.051690 19994 master.cpp:4978] Launching task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546 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 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.052912 20001 slave.cpp:1803] Got assigned task '1' for
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.053822 20001 slave.cpp:2071] Authorizing task '1' for
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.053925 20001 slave.cpp:7264] Authorizing framework principal
'test-principal' to launch task 1
3: I1121 18:25:09.054957 20010 slave.cpp:2239] Launching task '1' for framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.055605 20010 paths.cpp:621] Trying to chown
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
to user 'mesos'
3: I1121 18:25:09.055852 20010 slave.cpp:7738] Launching executor '1' of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-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_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
3: I1121 18:25:09.056905 20010 slave.cpp:2467] Queued task '1' for executor '1'
of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.056993 20010 slave.cpp:958] Successfully attached
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
to virtual path
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/latest'
3: I1121 18:25:09.057029 20010 slave.cpp:958] Successfully attached
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
to virtual path
'/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/latest'
3: I1121 18:25:09.057095 20010 slave.cpp:958] Successfully attached
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
to virtual path
'/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
3: I1121 18:25:09.057632 20010 slave.cpp:2930] Launching container
6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55 for executor '1' of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.058018 20009 containerizer.cpp:1195] Starting container
6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55
3: I1121 18:25:09.058908 20009 containerizer.cpp:1367] Checkpointed
ContainerConfig at
'/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/containers/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55/config'
3: I1121 18:25:09.058938 20009 containerizer.cpp:2919] Transitioning the state
of container 6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55 from PROVISIONING to PREPARING
3: I1121 18:25:09.063305 20004 containerizer.cpp:1836] 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:39546"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/HealthCheckTest_HealthyTaskNonShell_Xaz0tE\/slaves\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0\/frameworks\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000\/executors\/1\/runs\/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiI2Y2U0YWJlMC0yZjdiLTRkOTAtYjRkNS01N2IwZWFiZjdlNTUiLCJlaWQiOiIxIiwiZmlkIjoiMmIzYmJmZDQtZDUwYi00OGFlLWFlM2ItZThkYWFkZmQ3YWI2LTAwMDAifQ.MZcXbHfDtYiorOHEnEJPwiXgW0TE9Ou1NFiR5FuXhS8"},{"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":"2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(67)@172.17.0.3:39546"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/HealthCheckTest_HealthyTaskNonShell_Xaz0tE\/slaves\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0\/frameworks\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000\/executors\/1\/runs\/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/HealthCheckTest_HealthyTaskNonShell_Xaz0tE\/slaves\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0\/frameworks\/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000\/executors\/1\/runs\/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55"}"
--pipe_read="8" --pipe_write="9"
--runtime_directory="/tmp/HealthCheckTest_HealthyTaskNonShell_WDxsNu/containers/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55"
--unshare_namespace_mnt="false"'
3: I1121 18:25:09.065906 20004 launcher.cpp:140] Forked child with pid '21300'
for container '6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55'
3: I1121 18:25:09.066468 20004 containerizer.cpp:2919] Transitioning the state
of container 6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55 from PREPARING to ISOLATING
3: I1121 18:25:09.068222 20010 containerizer.cpp:2919] Transitioning the state
of container 6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55 from ISOLATING to FETCHING
3: I1121 18:25:09.068481 20010 fetcher.cpp:379] Starting to fetch URIs for
container: 6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55, directory:
/tmp/HealthCheckTest_HealthyTaskNonShell_Xaz0tE/slaves/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0/frameworks/2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000/executors/1/runs/6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55
3: I1121 18:25:09.069684 20009 containerizer.cpp:2919] Transitioning the state
of container 6ce4abe0-2f7b-4d90-b4d5-57b0eabf7e55 from FETCHING to RUNNING
3: I1121 18:25:09.361515 21301 exec.cpp:162] Version: 1.5.0
3: I1121 18:25:09.372846 19991 slave.cpp:4063] Got registration for executor
'1' of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.375429 20001 slave.cpp:2669] Sending queued task '1' to
executor '1' of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 at
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.378192 21319 exec.cpp:237] Executor registered on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.383925 21324 executor.cpp:171] Received SUBSCRIBED event
3: I1121 18:25:09.386134 21324 executor.cpp:175] Subscribed executor on
f11955b92810
3: I1121 18:25:09.386514 21324 executor.cpp:171] Received LAUNCH event
3: I1121 18:25:09.389545 21324 executor.cpp:637] Starting task 1
3: I1121 18:25:09.393920 19988 slave.cpp:4517] Handling status update
TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.396554 19991 task_status_update_manager.cpp:328] Received
task status update TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.396613 19991 task_status_update_manager.cpp:507] Creating
StatusUpdate stream for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.397258 19991 task_status_update_manager.cpp:383] Forwarding
task status update TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to the agent
3: I1121 18:25:09.397503 19990 slave.cpp:5000] Forwarding the update
TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to [email protected]:39546
3: I1121 18:25:09.397802 19990 slave.cpp:4893] Task status update manager
successfully handled status update TASK_STARTING (UUID:
fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.397897 19990 slave.cpp:4909] Sending acknowledgement for
status update TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99) for
task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.398041 19997 master.cpp:7268] Status update TASK_STARTING
(UUID: fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.398150 19997 master.cpp:7330] Forwarding status update
TASK_STARTING (UUID: fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.398325 19997 master.cpp:9523] Updating the state of task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (latest state:
TASK_STARTING, status update state: TASK_STARTING)
3: I1121 18:25:09.398584 19995 sched.cpp:1029] Scheduler::statusUpdate took
96665ns
3: I1121 18:25:09.398952 19994 master.cpp:5793] Processing ACKNOWLEDGE call
fe137408-b280-47ed-b494-5d72e13d8d99 for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546 on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.399374 19992 task_status_update_manager.cpp:401] Received
task status update acknowledgement (UUID: fe137408-b280-47ed-b494-5d72e13d8d99)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.399709 19992 slave.cpp:3801] Task status update manager
successfully handled status update acknowledgement (UUID:
fe137408-b280-47ed-b494-5d72e13d8d99) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.412643 21324 executor.cpp:477] Running
'/mesos/build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
3: I1121 18:25:09.416538 21324 executor.cpp:650] Forked command at 21327
3: I1121 18:25:09.422845 19998 slave.cpp:4517] Handling status update
TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.425179 19997 task_status_update_manager.cpp:328] Received
task status update TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.425410 19997 task_status_update_manager.cpp:383] Forwarding
task status update TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to the agent
3: I1121 18:25:09.425760 20002 slave.cpp:5000] Forwarding the update
TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to [email protected]:39546
3: I1121 18:25:09.426116 20002 slave.cpp:4893] Task status update manager
successfully handled status update TASK_RUNNING (UUID:
7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.426206 20002 slave.cpp:4909] Sending acknowledgement for
status update TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for
task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.426357 19995 master.cpp:7268] Status update TASK_RUNNING
(UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.426460 19995 master.cpp:7330] Forwarding status update
TASK_RUNNING (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.426707 19995 master.cpp:9523] Updating the state of task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (latest state:
TASK_RUNNING, status update state: TASK_RUNNING)
3: I1121 18:25:09.426976 20002 sched.cpp:1029] Scheduler::statusUpdate took
85993ns
3: I1121 18:25:09.427299 20006 master.cpp:5793] Processing ACKNOWLEDGE call
7a6e4a11-9b60-4668-851c-fc378a8fb8c6 for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546 on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.427739 19998 task_status_update_manager.cpp:401] Received
task status update acknowledgement (UUID: 7a6e4a11-9b60-4668-851c-fc378a8fb8c6)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.428146 20007 slave.cpp:3801] Task status update manager
successfully handled status update acknowledgement (UUID:
7a6e4a11-9b60-4668-851c-fc378a8fb8c6) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.461408 21318 checker_process.cpp:798] COMMAND health check
for task '1' returned: 0
3: I1121 18:25:09.461823 21321 executor.cpp:338] Received task health update,
healthy: true
3: I1121 18:25:09.467824 19993 slave.cpp:4517] Handling status update
TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 in health
state healthy of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.470255 20005 task_status_update_manager.cpp:328] Received
task status update TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e)
for task 1 in health state healthy of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.470489 20005 task_status_update_manager.cpp:383] Forwarding
task status update TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e)
for task 1 in health state healthy of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to the agent
3: I1121 18:25:09.470767 19994 slave.cpp:5000] Forwarding the update
TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 in health
state healthy of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to
[email protected]:39546
3: I1121 18:25:09.471110 19994 slave.cpp:4893] Task status update manager
successfully handled status update TASK_RUNNING (UUID:
ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 in health state healthy of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.471168 19994 slave.cpp:4909] Sending acknowledgement for
status update TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for
task 1 in health state healthy of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 to executor(1)@172.17.0.3:43960
3: I1121 18:25:09.471354 20002 master.cpp:7268] Status update TASK_RUNNING
(UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 in health state healthy
of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 from agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.471416 20002 master.cpp:7330] Forwarding status update
TASK_RUNNING (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 in health
state healthy of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.471626 20002 master.cpp:9523] Updating the state of task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (latest state:
TASK_RUNNING, status update state: TASK_RUNNING)
3: I1121 18:25:09.471959 19988 sched.cpp:1029] Scheduler::statusUpdate took
113728ns
3: I1121 18:25:09.472343 20006 master.cpp:5793] Processing ACKNOWLEDGE call
ac0c8b84-95a0-4be4-8ed1-5cb0e977067e for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546 on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0
3: I1121 18:25:09.472575 19986 sched.cpp:2009] Asked to stop the driver
3: I1121 18:25:09.472714 19993 sched.cpp:1191] Stopping framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.472950 19998 task_status_update_manager.cpp:401] Received
task status update acknowledgement (UUID: ac0c8b84-95a0-4be4-8ed1-5cb0e977067e)
for task 1 of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.473140 20008 master.cpp:8813] Processing TEARDOWN call for
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546
3: I1121 18:25:09.473170 20008 master.cpp:8825] Removing framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546
3: I1121 18:25:09.473183 20008 master.cpp:3333] Deactivating framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (default) at
[email protected]:39546
3: I1121 18:25:09.473317 19987 slave.cpp:3801] Task status update manager
successfully handled status update acknowledgement (UUID:
ac0c8b84-95a0-4be4-8ed1-5cb0e977067e) for task 1 of framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.473412 20009 hierarchical.cpp:419] Deactivated framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.473460 19987 slave.cpp:3267] Asked to shut down framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 by [email protected]:39546
3: I1121 18:25:09.473466 20008 master.cpp:9523] Updating the state of task 1 of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 (latest state: TASK_KILLED,
status update state: TASK_KILLED)
3: I1121 18:25:09.473507 19987 slave.cpp:3292] Shutting down framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
3: I1121 18:25:09.473574 19987 slave.cpp:5876] Shutting down executor '1' of
framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 at
executor(1)@172.17.0.3:43960
3: I1121 18:25:09.474409 21306 exec.cpp:435] Executor asked to shutdown
3: I1121 18:25:09.474340 20008 master.cpp:9617] Removing task 1 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
of framework 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000 on agent
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 at slave(67)@172.17.0.3:39546
(f11955b92810)
3: I1121 18:25:09.474740 20010 hierarchical.cpp:1132] 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 2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-S0 from framework
2b3bbfd4-d50b-48ae-ae3b-e8daadfd7ab6-0000
Build step 'Execute shell' marked build as failure
