You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@mesos.apache.org by "Andrei Sekretenko (Jira)" <ji...@apache.org> on 2019/10/30 12:56:00 UTC
[jira] [Commented] (MESOS-9359) Test
`DefaultExecutorTest.MaxCompletionTime` is flaky.
[ https://issues.apache.org/jira/browse/MESOS-9359?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16962998#comment-16962998 ]
Andrei Sekretenko commented on MESOS-9359:
------------------------------------------
Observed the same failure again:
{code}
[ RUN ] MesosContainerizer/DefaultExecutorTest.MaxCompletionTime/0
I1029 11:43:47.753170 427447744 cluster.cpp:176] Creating default 'local' authorizer
I1029 11:43:47.754923 30564352 master.cpp:440] Master 76f01095-30e8-488f-993a-a2cd92ba3528 (Jenkinss-Mac-mini.local) started on 10.0.49.4:61465
I1029 11:43:47.754952 30564352 master.cpp:443] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/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_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_allocatable_resources="cpus:0.01|mem:32" --port="5050" --publish_per_framework_metrics="true" --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" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/master" --zk_session_timeout="10secs"
I1029 11:43:47.755208 30564352 master.cpp:492] Master only allowing authenticated frameworks to register
I1029 11:43:47.755223 30564352 master.cpp:498] Master only allowing authenticated agents to register
I1029 11:43:47.755231 30564352 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I1029 11:43:47.755241 30564352 credentials.hpp:37] Loading credentials for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/credentials'
I1029 11:43:47.755479 30564352 master.cpp:548] Using default 'crammd5' authenticator
I1029 11:43:47.755595 30564352 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1029 11:43:47.755692 30564352 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1029 11:43:47.755796 30564352 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1029 11:43:47.755863 30564352 master.cpp:629] Authorization enabled
I1029 11:43:47.756098 28418048 hierarchical.cpp:244] Initialized hierarchical allocator process
I1029 11:43:47.756135 30027776 whitelist_watcher.cpp:77] No whitelist given
I1029 11:43:47.757712 28954624 master.cpp:2141] Elected as the leading master!
I1029 11:43:47.757737 28954624 master.cpp:1664] Recovering from registrar
I1029 11:43:47.757805 31100928 registrar.cpp:339] Recovering registrar
I1029 11:43:47.758086 31100928 registrar.cpp:383] Successfully fetched the registry (0B) in 260096ns
I1029 11:43:47.758225 31100928 registrar.cpp:487] Applied 1 operations in 37891ns; attempting to update the registry
I1029 11:43:47.758467 27344896 registrar.cpp:544] Successfully updated the registry in 198912ns
I1029 11:43:47.758539 27344896 registrar.cpp:416] Successfully recovered registrar
I1029 11:43:47.758762 31100928 hierarchical.cpp:284] Skipping recovery of hierarchical allocator: nothing to recover
I1029 11:43:47.758744 27881472 master.cpp:1790] Recovered 0 agents from the registry (155B); allowing 10mins for agents to reregister
W1029 11:43:47.762238 427447744 process.cpp:2829] Attempted to spawn already running process files@10.0.49.4:61465
I1029 11:43:47.763036 427447744 containerizer.cpp:314] Using isolation { environment_secret, filesystem/posix, posix/mem, posix/cpu }
I1029 11:43:47.763331 427447744 provisioner.cpp:292] Using default backend 'copy'
I1029 11:43:47.764209 427447744 cluster.cpp:510] Creating default 'local' authorizer
I1029 11:43:47.765164 27881472 slave.cpp:265] Mesos agent started on (926)@10.0.49.4:61465
I1029 11:43:47.765188 27881472 slave.cpp:266] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/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="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/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="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/jwt_secret_key" --launcher="posix" --launcher_dir="/Users/jenkins/workspace/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mac/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --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]" --runtime_dir="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --version="false" --work_dir="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P" --zk_session_timeout="10secs"
I1029 11:43:47.765513 27881472 credentials.hpp:86] Loading credential for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/credential'
I1029 11:43:47.765677 27881472 slave.cpp:298] Agent using credential for: test-principal
I1029 11:43:47.765712 27881472 credentials.hpp:37] Loading credentials for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/exbCxL/lCNMxV/http_credentials'
I1029 11:43:47.765966 27881472 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I1029 11:43:47.766042 27881472 http.cpp:986] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I1029 11:43:47.766113 427447744 scheduler.cpp:189] Version: 1.8.2
I1029 11:43:47.766230 27881472 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1029 11:43:47.766242 31100928 scheduler.cpp:342] Using default 'basic' HTTP authenticatee
I1029 11:43:47.766328 27881472 http.cpp:986] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I1029 11:43:47.766405 27881472 http.cpp:965] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1029 11:43:47.766450 27881472 http.cpp:986] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I1029 11:43:47.766670 27881472 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I1029 11:43:47.766677 31100928 scheduler.cpp:525] New master detected at master@10.0.49.4:61465
I1029 11:43:47.766696 31100928 scheduler.cpp:534] Waiting for 0ns before initiating a re-(connection) attempt with the master
I1029 11:43:47.767110 27881472 slave.cpp:613] 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"}]
I1029 11:43:47.767251 27881472 slave.cpp:621] Agent attributes: [ ]
I1029 11:43:47.767264 27881472 slave.cpp:630] Agent hostname: Jenkinss-Mac-mini.local
I1029 11:43:47.767565 28954624 task_status_update_manager.cpp:181] Pausing sending task status updates
I1029 11:43:47.767626 27344896 status_update_manager_process.hpp:379] Pausing operation status update manager
I1029 11:43:47.768101 29491200 state.cpp:67] Recovering state from '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/meta'
I1029 11:43:47.768226 27344896 scheduler.cpp:416] Connected with the master at http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.768337 30027776 slave.cpp:7265] Finished recovering checkpointed state from '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/meta', beginning agent recovery
I1029 11:43:47.768528 28954624 task_status_update_manager.cpp:207] Recovering task status update manager
I1029 11:43:47.768638 29491200 scheduler.cpp:246] Adding authentication headers to SUBSCRIBE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.768772 30027776 containerizer.cpp:797] Recovering Mesos containers
I1029 11:43:47.768879 30564352 scheduler.cpp:600] Sending SUBSCRIBE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.768982 30027776 containerizer.cpp:1123] Recovering isolators
I1029 11:43:47.769279 31100928 containerizer.cpp:1162] Recovering provisioner
I1029 11:43:47.769520 27881472 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:47.769734 28418048 provisioner.cpp:516] Provisioner recovery complete
I1029 11:43:47.770162 29491200 composing.cpp:339] Finished recovering all containerizers
I1029 11:43:47.770352 30027776 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64859
I1029 11:43:47.770411 28418048 slave.cpp:7727] Recovering executors
I1029 11:43:47.770462 28418048 slave.cpp:7880] Finished recovery
I1029 11:43:47.770515 30027776 master.cpp:2568] Received subscription request for HTTP framework 'default'
I1029 11:43:47.770548 30027776 master.cpp:2213] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1029 11:43:47.770869 30564352 master.cpp:2703] Subscribing framework 'default' with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I1029 11:43:47.771674 28418048 task_status_update_manager.cpp:181] Pausing sending task status updates
I1029 11:43:47.771693 28954624 status_update_manager_process.hpp:379] Pausing operation status update manager
I1029 11:43:47.771697 31100928 slave.cpp:1258] New master detected at master@10.0.49.4:61465
I1029 11:43:47.771777 31100928 slave.cpp:1323] Detecting new master
I1029 11:43:47.771807 30564352 master.cpp:10545] Adding framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) with roles { } suppressed
I1029 11:43:47.772204 30027776 hierarchical.cpp:373] Added framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.772495 30027776 hierarchical.cpp:1600] Performed allocation for 0 agents in 49893ns
I1029 11:43:47.772730 27881472 scheduler.cpp:847] Enqueuing event SUBSCRIBED received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.773034 27881472 scheduler.cpp:847] Enqueuing event HEARTBEAT received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.774474 30027776 slave.cpp:1350] Authenticating with master master@10.0.49.4:61465
I1029 11:43:47.774531 30027776 slave.cpp:1359] Using default CRAM-MD5 authenticatee
I1029 11:43:47.774660 28954624 authenticatee.cpp:121] Creating new client SASL connection
I1029 11:43:47.774863 27344896 master.cpp:10315] Authenticating slave(926)@10.0.49.4:61465
I1029 11:43:47.774921 31100928 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1651)@10.0.49.4:61465
I1029 11:43:47.775064 28418048 authenticator.cpp:98] Creating new server SASL connection
I1029 11:43:47.775171 27881472 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1029 11:43:47.775193 27881472 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1029 11:43:47.775245 29491200 authenticator.cpp:204] Received SASL authentication start
I1029 11:43:47.775377 29491200 authenticator.cpp:326] Authentication requires more steps
I1029 11:43:47.775434 30027776 authenticatee.cpp:259] Received SASL authentication step
I1029 11:43:47.775504 30564352 authenticator.cpp:232] Received SASL authentication step
I1029 11:43:47.775534 30564352 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'Jenkinss-Mac-mini.local' server FQDN: 'Jenkinss-Mac-mini.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1029 11:43:47.775549 30564352 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1029 11:43:47.775583 30564352 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1029 11:43:47.775602 30564352 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'Jenkinss-Mac-mini.local' server FQDN: 'Jenkinss-Mac-mini.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1029 11:43:47.775614 30564352 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1029 11:43:47.775640 30564352 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1029 11:43:47.775666 30564352 authenticator.cpp:318] Authentication success
I1029 11:43:47.775718 28954624 authenticatee.cpp:299] Authentication success
I1029 11:43:47.775779 27344896 master.cpp:10347] Successfully authenticated principal 'test-principal' at slave(926)@10.0.49.4:61465
I1029 11:43:47.775807 31100928 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1651)@10.0.49.4:61465
I1029 11:43:47.775955 28418048 slave.cpp:1450] Successfully authenticated with master master@10.0.49.4:61465
I1029 11:43:47.776217 28418048 slave.cpp:1900] Will retry registration in 12.200101ms if necessary
I1029 11:43:47.776271 27344896 master.cpp:6854] Received register agent message from slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.776423 27344896 master.cpp:4053] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I1029 11:43:47.776698 30564352 master.cpp:6921] Authorized registration of agent at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.776757 30564352 master.cpp:7036] Registering agent at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) with id 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:47.777009 27881472 registrar.cpp:487] Applied 1 operations in 121042ns; attempting to update the registry
I1029 11:43:47.777263 29491200 registrar.cpp:544] Successfully updated the registry in 212992ns
I1029 11:43:47.777369 31100928 master.cpp:7084] Admitted agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.777637 31100928 master.cpp:7129] Registered agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1029 11:43:47.777739 27881472 slave.cpp:1483] Registered with master master@10.0.49.4:61465; given agent ID 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:47.777747 28418048 hierarchical.cpp:630] Added agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 (Jenkinss-Mac-mini.local) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1029 11:43:47.777796 27344896 task_status_update_manager.cpp:188] Resuming sending task status updates
I1029 11:43:47.778131 28418048 hierarchical.cpp:1600] Performed allocation for 1 agents in 299915ns
I1029 11:43:47.778287 28954624 master.cpp:10130] Sending offers [ 76f01095-30e8-488f-993a-a2cd92ba3528-O0 ] to framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:43:47.778298 27881472 slave.cpp:1518] Checkpointing SlaveInfo to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/meta/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/slave.info'
I1029 11:43:47.778306 29491200 status_update_manager_process.hpp:385] Resuming operation status update manager
I1029 11:43:47.778916 27344896 scheduler.cpp:847] Enqueuing event OFFERS received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.779430 27881472 slave.cpp:1570] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"oDFwFnhARkGePyyOuW89oQ=="},"slave_id":{"value":"76f01095-30e8-488f-993a-a2cd92ba3528-S0"},"update_oversubscribed_resources":false}
I1029 11:43:47.780077 30027776 master.cpp:8215] Ignoring update on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) as it reports no changes
I1029 11:43:47.781623 31100928 scheduler.cpp:246] Adding authentication headers to ACCEPT call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.781841 30027776 scheduler.cpp:600] Sending ACCEPT call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.782359 27344896 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:47.783022 28418048 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:47.783502 28418048 master.cpp:12412] Removing offer 76f01095-30e8-488f-993a-a2cd92ba3528-O0
I1029 11:43:47.783859 28418048 master.cpp:4590] Processing ACCEPT call for offers: [ 76f01095-30e8-488f-993a-a2cd92ba3528-O0 ] on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) for framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:43:47.783942 28418048 master.cpp:3607] Authorizing framework principal 'test-principal' to launch task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f
I1029 11:43:47.784082 28418048 master.cpp:3607] Authorizing framework principal 'test-principal' to launch task 7a03a973-6f47-41f4-b5f9-46fdaab70044
I1029 11:43:47.784234 28418048 master.cpp:3607] Authorizing framework principal 'test-principal' to launch task baea9a8f-c34f-491b-bd03-fa14f85bf3ab
I1029 11:43:47.786242 28418048 master.cpp:4125] Adding executor 'default' with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.786386 28418048 master.cpp:4151] Adding task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.786531 28418048 master.cpp:4151] Adding task 7a03a973-6f47-41f4-b5f9-46fdaab70044 with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.786664 28418048 master.cpp:4151] Adding task baea9a8f-c34f-491b-bd03-fa14f85bf3ab with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.786803 28418048 master.cpp:5792] Launching task group { 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab } of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) with resources cpus(allocated: *):0.3; mem(allocated: *):96; disk(allocated: *):96 on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) on new executor
I1029 11:43:47.787029 28954624 hierarchical.cpp:1524] Allocation paused
I1029 11:43:47.787271 28954624 hierarchical.cpp:1250] 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 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.787340 28954624 hierarchical.cpp:1296] Framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 filtered agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 for 5secs
I1029 11:43:47.787402 27344896 slave.cpp:2037] Got assigned task group containing tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] for framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.787459 28954624 hierarchical.cpp:1534] Allocation resumed
I1029 11:43:47.788422 27344896 slave.cpp:2411] Authorizing task group containing tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] for framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.788506 27344896 slave.cpp:9217] Authorizing framework principal 'test-principal' to launch task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f
I1029 11:43:47.788628 27344896 slave.cpp:9217] Authorizing framework principal 'test-principal' to launch task 7a03a973-6f47-41f4-b5f9-46fdaab70044
I1029 11:43:47.788718 27344896 slave.cpp:9217] Authorizing framework principal 'test-principal' to launch task baea9a8f-c34f-491b-bd03-fa14f85bf3ab
I1029 11:43:47.790601 27344896 slave.cpp:2854] Launching task group containing tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] for framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.790729 27344896 paths.cpp:801] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1' for user 'jenkins'
I1029 11:43:47.792002 27344896 slave.cpp:9727] Launching executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-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"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1'
I1029 11:43:47.792749 27344896 slave.cpp:3086] Queued task group containing tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] for executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.792907 27344896 slave.cpp:991] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1' to virtual path '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/latest'
I1029 11:43:47.793284 27344896 slave.cpp:991] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1' to virtual path '/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/latest'
I1029 11:43:47.793313 27344896 slave.cpp:991] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1' to virtual path '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1'
I1029 11:43:47.793480 27344896 slave.cpp:3534] Launching container f2ac6d81-c89d-4423-940e-84cee380b6d1 for executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.793705 30564352 containerizer.cpp:1358] Starting container f2ac6d81-c89d-4423-940e-84cee380b6d1
I1029 11:43:47.794718 30564352 containerizer.cpp:1530] Checkpointed ContainerConfig at '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/config'
I1029 11:43:47.794745 30564352 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1 from PROVISIONING to PREPARING
I1029 11:43:47.797997 31100928 containerizer.cpp:2056] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-default-executor","--launcher_dir=/Users/jenkins/workspace/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mac/mesos/build/src"],"shell":false,"value":"/Users/jenkins/workspace/workspace/mesos/Mesos_CI-build/FLAG/SSL/label/mac/mesos/build/src/mesos-default-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"10.0.49.4:61465"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiJmMmFjNmQ4MS1jODlkLTQ0MjMtOTQwZS04NGNlZTM4MGI2ZDEiLCJlaWQiOiJkZWZhdWx0IiwiZmlkIjoiNzZmMDEwOTUtMzBlOC00ODhmLTk5M2EtYTJjZDkyYmEzNTI4LTAwMDAifQ.gmDL1l9NvG0q8USohaHuqNvqrZM2teK_LGsG32F3xOo"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"default"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"76f01095-30e8-488f-993a-a2cd92ba3528-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"76f01095-30e8-488f-993a-a2cd92ba3528-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(926)@10.0.49.4:61465"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1"}]},"task_environment":{},"user":"jenkins","working_directory":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1"}" --pipe_read="40" --pipe_write="43" --runtime_directory="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1"'
I1029 11:43:47.799466 31100928 launcher.cpp:145] Forked child with pid '7829' for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1'
I1029 11:43:47.800384 31100928 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1 from PREPARING to ISOLATING
I1029 11:43:47.802825 27344896 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1 from ISOLATING to FETCHING
I1029 11:43:47.803360 27881472 fetcher.cpp:369] Starting to fetch URIs for container: f2ac6d81-c89d-4423-940e-84cee380b6d1, directory: /var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1
I1029 11:43:47.804307 28418048 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1 from FETCHING to RUNNING
[warn] kq_init: detected broken kqueue; not using.: Undefined error: 0
I1029 11:43:47.945802 45694976 executor.cpp:206] Version: 1.8.2
I1029 11:43:47.953672 29491200 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:47.954928 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64862
I1029 11:43:47.955057 28418048 slave.cpp:4852] Received Subscribe request for HTTP executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.956288 29491200 slave.cpp:3345] Sending queued task group containing tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] to executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (via HTTP)
I1029 11:43:47.957525 45158400 default_executor.cpp:204] Received SUBSCRIBED event
I1029 11:43:47.958259 45158400 default_executor.cpp:208] Subscribed executor on Jenkinss-Mac-mini.local
I1029 11:43:47.958532 45158400 default_executor.cpp:204] Received LAUNCH_GROUP event
I1029 11:43:47.959085 45694976 default_executor.cpp:432] Setting 'MESOS_CONTAINER_IP' to: 10.0.49.4
I1029 11:43:47.962890 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:47.963388 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:47.963510 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:47.963687 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:47.963838 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:47.964118 30564352 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:47.966153 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:47.966277 28418048 slave.cpp:5551] Handling status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.966605 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64864
I1029 11:43:47.966836 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64864
I1029 11:43:47.967033 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64864
I1029 11:43:47.967181 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:47.967272 28418048 slave.cpp:5551] Handling status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.967469 28418048 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:47.967555 28418048 slave.cpp:5551] Handling status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.967919 31100928 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.967954 31100928 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.967978 28418048 http.cpp:2454] Processing LAUNCH_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0'
I1029 11:43:47.968274 28418048 http.cpp:2454] Processing LAUNCH_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28'
I1029 11:43:47.968392 31100928 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:47.968490 28418048 http.cpp:2454] Processing LAUNCH_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822'
I1029 11:43:47.968782 31100928 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.968817 31100928 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.968888 28418048 slave.cpp:6056] Forwarding the update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:47.969113 28954624 master.cpp:8703] Status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.969172 28954624 master.cpp:8760] Forwarding status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.969210 28418048 slave.cpp:5940] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.969177 31100928 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:47.969287 30027776 containerizer.cpp:1320] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0' for user 'jenkins'
I1029 11:43:47.969506 28954624 master.cpp:11761] Updating the state of task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1029 11:43:47.969512 31100928 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.969674 28418048 slave.cpp:6056] Forwarding the update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:47.969955 31100928 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.970258 30027776 containerizer.cpp:1358] Starting container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0
I1029 11:43:47.970263 28418048 slave.cpp:5940] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.970513 28954624 master.cpp:8703] Status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.970635 30564352 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.971125 44621824 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:47.970860 31100928 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:47.970849 28954624 master.cpp:8760] Forwarding status update TASK_STARTING (Status UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.971557 29491200 slave.cpp:6056] Forwarding the update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:47.971668 29491200 slave.cpp:5940] Task status update manager successfully handled status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.971854 27344896 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.971880 28954624 master.cpp:11761] Updating the state of task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1029 11:43:47.971999 28954624 master.cpp:8703] Status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:47.972033 28954624 master.cpp:8760] Forwarding status update TASK_STARTING (Status UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.972226 28954624 master.cpp:11761] Updating the state of task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1029 11:43:47.972352 30027776 containerizer.cpp:1530] Checkpointed ContainerConfig at '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0/config'
I1029 11:43:47.972398 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 from PROVISIONING to PREPARING
I1029 11:43:47.972707 27344896 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.972859 30027776 containerizer.cpp:1320] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28' for user 'jenkins'
I1029 11:43:47.973157 42475520 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:47.973567 30027776 containerizer.cpp:1358] Starting container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28
I1029 11:43:47.973824 43012096 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:47.974135 28954624 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:47.974161 27344896 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.974512 27344896 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.974751 27344896 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.974846 30027776 containerizer.cpp:1530] Checkpointed ContainerConfig at '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28/config'
I1029 11:43:47.974870 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 from PROVISIONING to PREPARING
I1029 11:43:47.975232 30027776 containerizer.cpp:1320] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822' for user 'jenkins'
I1029 11:43:47.975277 27344896 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.975801 27344896 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:47.975900 29491200 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.975914 30027776 containerizer.cpp:1358] Starting container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822
I1029 11:43:47.975920 27344896 master.cpp:6465] Processing ACKNOWLEDGE call for status 00f66b88-4e5b-4c5d-b9ba-82af3d27c644 for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:47.976107 27344896 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:47.976418 29491200 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:47.976513 31100928 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.976912 29491200 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: 00f66b88-4e5b-4c5d-b9ba-82af3d27c644) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.977164 30027776 containerizer.cpp:1530] Checkpointed ContainerConfig at '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822/config'
I1029 11:43:47.977206 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 from PROVISIONING to PREPARING
I1029 11:43:47.977295 27881472 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:47.978046 27344896 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:47.978158 27344896 master.cpp:6465] Processing ACKNOWLEDGE call for status 29e2dda1-2fdf-4a66-abf8-d4616fa0857b for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:47.978399 28418048 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.978744 30564352 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: 29e2dda1-2fdf-4a66-abf8-d4616fa0857b) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.978744 31100928 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:47.978920 31100928 master.cpp:6465] Processing ACKNOWLEDGE call for status dba8abaa-439e-4506-9c93-3f553873e6ed for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:47.979049 30027776 containerizer.cpp:2056] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"10.0.49.4"}]},"task_environment":{},"user":"jenkins","working_directory":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0"}" --pipe_read="45" --pipe_write="46" --runtime_directory="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0"'
I1029 11:43:47.979269 27881472 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.979499 30564352 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: dba8abaa-439e-4506-9c93-3f553873e6ed) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:47.981012 30027776 launcher.cpp:145] Forked child with pid '7857' for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0'
I1029 11:43:47.982195 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 from PREPARING to ISOLATING
I1029 11:43:47.984869 30027776 containerizer.cpp:2056] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"10.0.49.4"}]},"task_environment":{},"user":"jenkins","working_directory":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28"}" --pipe_read="47" --pipe_write="48" --runtime_directory="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28"'
I1029 11:43:47.986306 30027776 launcher.cpp:145] Forked child with pid '7859' for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28'
I1029 11:43:47.987674 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 from PREPARING to ISOLATING
I1029 11:43:47.989830 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 from ISOLATING to FETCHING
I1029 11:43:47.990952 27344896 fetcher.cpp:369] Starting to fetch URIs for container: f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0, directory: /var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0
I1029 11:43:47.991158 30027776 containerizer.cpp:2056] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"sleep 1000"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"10.0.49.4"}]},"task_environment":{},"user":"jenkins","working_directory":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822"}" --pipe_read="49" --pipe_write="50" --runtime_directory="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822"'
I1029 11:43:47.993409 30027776 launcher.cpp:145] Forked child with pid '7867' for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822'
I1029 11:43:47.994449 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 from PREPARING to ISOLATING
I1029 11:43:47.996104 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 from ISOLATING to FETCHING
I1029 11:43:47.997856 27344896 fetcher.cpp:369] Starting to fetch URIs for container: f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28, directory: /var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28
I1029 11:43:47.997939 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 from FETCHING to RUNNING
I1029 11:43:47.998318 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 from ISOLATING to FETCHING
I1029 11:43:48.000506 27344896 fetcher.cpp:369] Starting to fetch URIs for container: f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822, directory: /var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_f51r3P/slaves/76f01095-30e8-488f-993a-a2cd92ba3528-S0/frameworks/76f01095-30e8-488f-993a-a2cd92ba3528-0000/executors/default/runs/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822
I1029 11:43:48.000763 29491200 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 from FETCHING to RUNNING
I1029 11:43:48.001437 27881472 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 from FETCHING to RUNNING
I1029 11:43:48.009479 43012096 default_executor.cpp:667] Finished launching tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ] in child containers [ f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0, f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28, f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 ]
I1029 11:43:48.009552 43012096 default_executor.cpp:691] Waiting on child containers of tasks [ 5f1a4d5d-d55f-42e2-89e9-b734a78f413f, 7a03a973-6f47-41f4-b5f9-46fdaab70044, baea9a8f-c34f-491b-bd03-fa14f85bf3ab ]
I1029 11:43:48.011621 28418048 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:48.012079 28418048 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:48.012277 28418048 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:48.013411 42475520 default_executor.cpp:752] Waiting for child container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 of task '5f1a4d5d-d55f-42e2-89e9-b734a78f413f'
I1029 11:43:48.013604 42475520 default_executor.cpp:752] Waiting for child container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 of task '7a03a973-6f47-41f4-b5f9-46fdaab70044'
I1029 11:43:48.013690 42475520 default_executor.cpp:752] Waiting for child container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 of task 'baea9a8f-c34f-491b-bd03-fa14f85bf3ab'
I1029 11:43:48.016053 27881472 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:48.016234 27881472 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:48.016492 27881472 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:43:48.019081 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:48.019275 31100928 slave.cpp:5551] Handling status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.019771 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:48.019876 31100928 slave.cpp:5551] Handling status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.020148 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:48.020377 31100928 slave.cpp:5551] Handling status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.020963 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64867
I1029 11:43:48.021193 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64865
I1029 11:43:48.021394 31100928 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64866
I1029 11:43:48.021657 31100928 http.cpp:2677] Processing WAIT_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822'
I1029 11:43:48.022042 31100928 http.cpp:2677] Processing WAIT_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0'
I1029 11:43:48.022644 31100928 http.cpp:2677] Processing WAIT_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28'
I1029 11:43:48.023422 27344896 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.023505 27344896 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:48.023744 27344896 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.023813 27344896 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:48.023950 27344896 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.024016 31100928 slave.cpp:6056] Forwarding the update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:48.024017 27344896 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:48.024560 31100928 slave.cpp:5940] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.024587 29491200 master.cpp:8703] Status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:48.024729 29491200 master.cpp:8760] Forwarding status update TASK_RUNNING (Status UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.024737 31100928 slave.cpp:6056] Forwarding the update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:48.024830 31100928 slave.cpp:5940] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.024935 31100928 slave.cpp:6056] Forwarding the update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:48.025020 29491200 master.cpp:11761] Updating the state of task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1029 11:43:48.025022 31100928 slave.cpp:5940] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.025131 29491200 master.cpp:8703] Status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:48.025166 29491200 master.cpp:8760] Forwarding status update TASK_RUNNING (Status UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.025315 29491200 master.cpp:11761] Updating the state of task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1029 11:43:48.026017 29491200 master.cpp:8703] Status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:48.026087 29491200 master.cpp:8760] Forwarding status update TASK_RUNNING (Status UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.026315 29491200 master.cpp:11761] Updating the state of task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1029 11:43:48.028239 30027776 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.028554 45158400 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:48.029109 28954624 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.029392 42475520 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:48.029397 28954624 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.029922 27881472 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.030015 41938944 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:43:48.030177 27881472 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.030266 27881472 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.030690 27881472 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.030802 27881472 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.031085 27344896 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.031937 29491200 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:48.032168 29491200 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:48.032392 29491200 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:48.033864 31100928 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:48.034006 31100928 master.cpp:6465] Processing ACKNOWLEDGE call for status cba64842-6fed-4c7e-92e6-d9d1b33b6956 for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:48.034199 31100928 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:48.034271 31100928 master.cpp:6465] Processing ACKNOWLEDGE call for status 0b78bb7d-cf8f-4ebf-804d-11837253dd90 for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:48.034457 31100928 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:48.034534 31100928 master.cpp:6465] Processing ACKNOWLEDGE call for status 1385e0ce-c7b2-437a-9d5a-357429eae568 for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:48.034621 30027776 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.035117 27344896 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: cba64842-6fed-4c7e-92e6-d9d1b33b6956) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.035696 30564352 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.036188 30027776 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: 0b78bb7d-cf8f-4ebf-804d-11837253dd90) for task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.036343 30564352 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.036693 27881472 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: 1385e0ce-c7b2-437a-9d5a-357429eae568) for task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.149107 31100928 containerizer.cpp:3112] Container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 has exited
I1029 11:43:48.149137 31100928 containerizer.cpp:2576] Destroying container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 in RUNNING state
I1029 11:43:48.149149 31100928 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 from RUNNING to DESTROYING
I1029 11:43:48.149266 31100928 launcher.cpp:161] Asked to destroy container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0
I1029 11:43:48.154300 30027776 provisioner.cpp:649] Ignoring destroy request for unknown container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0
I1029 11:43:48.154381 29491200 containerizer.cpp:2894] Checkpointing termination state to nested container's runtime directory '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/3023f943-9271-4264-a14a-56645d0566f0/termination'
I1029 11:43:48.156263 31100928 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1/executor'
I1029 11:43:48.157306 27881472 http.cpp:1105] HTTP POST for /slave(926)/api/v1/executor from 10.0.49.4:64863
I1029 11:43:48.157433 27881472 slave.cpp:5551] Handling status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.158463 28954624 task_status_update_manager.cpp:328] Received task status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.158555 28954624 task_status_update_manager.cpp:383] Forwarding task status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to the agent
I1029 11:43:48.158710 30564352 slave.cpp:6056] Forwarding the update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 to master@10.0.49.4:61465
I1029 11:43:48.158874 30564352 slave.cpp:5940] Task status update manager successfully handled status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.158995 27881472 master.cpp:8703] Status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 from agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:48.159032 27881472 master.cpp:8760] Forwarding status update TASK_FINISHED (Status UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.159236 27881472 master.cpp:11761] Updating the state of task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I1029 11:43:48.159754 29491200 hierarchical.cpp:1250] 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 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.160135 28954624 scheduler.cpp:847] Enqueuing event UPDATE received from http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.160557 31100928 scheduler.cpp:246] Adding authentication headers to ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.160805 30027776 scheduler.cpp:600] Sending ACKNOWLEDGE call to http://10.0.49.4:61465/master/api/v1/scheduler
I1029 11:43:48.161267 27344896 process.cpp:3596] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1029 11:43:48.161823 29491200 http.cpp:1105] HTTP POST for /master/api/v1/scheduler from 10.0.49.4:64860
I1029 11:43:48.161917 29491200 master.cpp:6465] Processing ACKNOWLEDGE call for status d2c42ff2-35b5-430c-a348-da5f8ec4fdd0 for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:43:48.162065 29491200 master.cpp:11859] Removing task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:43:48.162356 28418048 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.162499 28418048 task_status_update_manager.cpp:538] Cleaning up status update stream for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.162873 30564352 slave.cpp:4690] Task status update manager successfully handled status update acknowledgement (UUID: d2c42ff2-35b5-430c-a348-da5f8ec4fdd0) for task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:43:48.162902 30564352 slave.cpp:10396] Completing task 5f1a4d5d-d55f-42e2-89e9-b734a78f413f
I1029 11:43:48.155825 43012096 default_executor.cpp:959] Child container f2ac6d81-c89d-4423-940e-84cee380b6d1.3023f943-9271-4264-a14a-56645d0566f0 of task '5f1a4d5d-d55f-42e2-89e9-b734a78f413f' completed in state TASK_FINISHED: Command exited with status 0
I1029 11:43:48.164722 43012096 default_executor.cpp:204] Received ACKNOWLEDGED event
I1029 11:44:06.001586 45158400 default_executor.cpp:1297] Killing task 7a03a973-6f47-41f4-b5f9-46fdaab70044 which exceeded its maximum completion time of 2secs
I1029 11:44:06.001718 45158400 default_executor.cpp:1130] Killing task 7a03a973-6f47-41f4-b5f9-46fdaab70044 running in child container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 with SIGTERM signal
I1029 11:44:06.001734 45158400 default_executor.cpp:1141] Scheduling escalation to SIGKILL in 0ns from now
I1029 11:44:06.002432 45158400 default_executor.cpp:1219] Task '7a03a973-6f47-41f4-b5f9-46fdaab70044' running in child container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 did not terminate after 0ns, sending SIGKILL to the container
../../src/tests/default_executor_tests.cpp:2034: Failure
Failed to wait 15secs for failedUpdate2
I1029 11:44:06.006541 28418048 hierarchical.cpp:1600] Performed allocation for 1 agents in 1.10683ms
I1029 11:44:06.007357 30027776 master.cpp:10130] Sending offers [ 76f01095-30e8-488f-993a-a2cd92ba3528-O1 ] to framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:44:06.008925 31100928 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:44:06.010767 29491200 master.cpp:1410] Framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) disconnected
I1029 11:44:06.010795 29491200 master.cpp:3296] Deactivating framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
W1029 11:44:06.011119 29491200 master.hpp:2582] Unable to send message to framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default): connection closed
I1029 11:44:06.011199 29491200 master.cpp:12412] Removing offer 76f01095-30e8-488f-993a-a2cd92ba3528-O1
I1029 11:44:06.011248 29491200 master.cpp:3273] Disconnecting framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:44:06.011268 29491200 master.cpp:1425] Giving framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default) 0ns to failover
I1029 11:44:06.011554 28954624 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:44:06.011981 28418048 hierarchical.cpp:479] Deactivated framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.012426 28418048 hierarchical.cpp:1250] 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 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.013293 29491200 master.cpp:9922] Framework failover timeout, removing framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:44:06.013345 29491200 master.cpp:10923] Removing framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (default)
I1029 11:44:06.013551 29491200 master.cpp:11761] Updating the state of task 7a03a973-6f47-41f4-b5f9-46fdaab70044 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1029 11:44:06.013811 29491200 master.cpp:11859] Removing task 7a03a973-6f47-41f4-b5f9-46fdaab70044 with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:44:06.014122 29491200 master.cpp:11761] Updating the state of task baea9a8f-c34f-491b-bd03-fa14f85bf3ab of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1029 11:44:06.014297 29491200 master.cpp:11859] Removing task baea9a8f-c34f-491b-bd03-fa14f85bf3ab with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:44:06.014508 29491200 master.cpp:11899] Removing executor 'default' with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:44:06.014853 28954624 slave.cpp:3914] Asked to shut down framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 by master@10.0.49.4:61465
I1029 11:44:06.014904 28954624 slave.cpp:3939] Shutting down framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.014923 28954624 slave.cpp:6996] Shutting down executor 'default' of framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 (via HTTP)
I1029 11:44:06.015235 27344896 hierarchical.cpp:1250] 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 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.015524 27344896 hierarchical.cpp:1250] 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 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.015818 27344896 hierarchical.cpp:1250] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 from framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.015868 27344896 hierarchical.cpp:1524] Allocation paused
I1029 11:44:06.016842 27344896 hierarchical.cpp:422] Removed framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000
I1029 11:44:06.016881 27344896 hierarchical.cpp:1534] Allocation resumed
I1029 11:44:06.019320 30564352 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64868
I1029 11:44:06.019731 30564352 http.cpp:2842] Processing KILL_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28'
I1029 11:44:06.020053 41938944 default_executor.cpp:204] Received SHUTDOWN event
I1029 11:44:06.020082 41938944 default_executor.cpp:1025] Shutting down
I1029 11:44:06.020112 41938944 default_executor.cpp:1130] Killing task baea9a8f-c34f-491b-bd03-fa14f85bf3ab running in child container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 with SIGTERM signal
I1029 11:44:06.020125 41938944 default_executor.cpp:1141] Scheduling escalation to SIGKILL in 3secs from now
I1029 11:44:06.020406 29491200 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64869
I1029 11:44:06.020709 29491200 http.cpp:2842] Processing KILL_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28'
I1029 11:44:06.022253 30027776 containerizer.cpp:2980] Sending Terminated: 15 to container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 in RUNNING state
I1029 11:44:06.022987 30027776 containerizer.cpp:2980] Sending Killed: 9 to container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 in RUNNING state
I1029 11:44:06.023033 28954624 process.cpp:3596] Handling HTTP event for process 'slave(926)' with path: '/slave(926)/api/v1'
I1029 11:44:06.028395 27881472 http.cpp:1105] HTTP POST for /slave(926)/api/v1 from 10.0.49.4:64870
I1029 11:44:06.028753 27881472 http.cpp:2842] Processing KILL_NESTED_CONTAINER call for container 'f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822'
I1029 11:44:06.030200 31100928 containerizer.cpp:2980] Sending Terminated: 15 to container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 in RUNNING state
../../src/tests/default_executor_tests.cpp:2017: Failure
Actual function call count doesn't match EXPECT_CALL(*scheduler, failure(_, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
../../src/tests/default_executor_tests.cpp:1971: Failure
Actual function call count doesn't match EXPECT_CALL(*scheduler, update(_, AllOf( TaskStatusUpdateTaskIdEq(taskInfo2), TaskStatusUpdateStateEq(v1::TASK_FAILED))))...
Expected: to be called once
Actual: never called - unsatisfied and active
../../src/tests/default_executor_tests.cpp:2009: Failure
Actual function call count doesn't match EXPECT_CALL(*scheduler, update(_, AllOf( TaskStatusUpdateTaskIdEq(taskInfo3), TaskStatusUpdateStateEq(v1::TASK_KILLED))))...
Expected: to be called once
Actual: never called - unsatisfied and active
I1029 11:44:06.032157 31100928 slave.cpp:912] Agent terminating
I1029 11:44:06.032202 31100928 slave.cpp:3914] Asked to shut down framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 by @0.0.0.0:0
W1029 11:44:06.032253 31100928 slave.cpp:3935] Ignoring shutdown framework 76f01095-30e8-488f-993a-a2cd92ba3528-0000 because it is terminating
I1029 11:44:06.033108 30564352 master.cpp:1295] Agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local) disconnected
I1029 11:44:06.033156 30564352 master.cpp:3333] Disconnecting agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:44:06.033193 30564352 master.cpp:3352] Deactivating agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 at slave(926)@10.0.49.4:61465 (Jenkinss-Mac-mini.local)
I1029 11:44:06.033356 31100928 hierarchical.cpp:821] Agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0 deactivated
I1029 11:44:06.033668 31100928 containerizer.cpp:2576] Destroying container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 in RUNNING state
I1029 11:44:06.033717 31100928 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 from RUNNING to DESTROYING
I1029 11:44:06.033881 31100928 launcher.cpp:161] Asked to destroy container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822
I1029 11:44:06.109856 28954624 containerizer.cpp:3112] Container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 has exited
I1029 11:44:06.109884 28954624 containerizer.cpp:2576] Destroying container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 in RUNNING state
I1029 11:44:06.109907 28954624 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 from RUNNING to DESTROYING
I1029 11:44:06.110026 28954624 launcher.cpp:161] Asked to destroy container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28
I1029 11:44:06.115437 27881472 provisioner.cpp:649] Ignoring destroy request for unknown container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28
I1029 11:44:06.115562 30564352 containerizer.cpp:2894] Checkpointing termination state to nested container's runtime directory '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/c1ab30cc-50c7-4254-85c4-12440df81c28/termination'
I1029 11:44:07.016477 29491200 hierarchical.cpp:1600] Performed allocation for 1 agents in 105015ns
I1029 11:44:08.025725 28418048 hierarchical.cpp:1600] Performed allocation for 1 agents in 111995ns
I1029 11:44:09.020686 45158400 default_executor.cpp:1219] Task 'baea9a8f-c34f-491b-bd03-fa14f85bf3ab' running in child container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 did not terminate after 3secs, sending SIGKILL to the container
I1029 11:44:09.021575 31637504 process.cpp:2754] Returning '404 Not Found' for '/slave(926)/api/v1'
W1029 11:44:09.022097 43548672 default_executor.cpp:1228] Escalation to SIGKILL the task 'baea9a8f-c34f-491b-bd03-fa14f85bf3ab' running in child container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 failed: The agent failed to send signal Killed: 9 (9) to the container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822: 404 Not Found.; Retrying in 1secs
I1029 11:44:09.028447 28418048 hierarchical.cpp:1600] Performed allocation for 1 agents in 58767ns
I1029 11:44:09.299892 41938944 default_executor.cpp:959] Child container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28 of task '7a03a973-6f47-41f4-b5f9-46fdaab70044' completed in state TASK_FAILED: Command terminated with signal Killed: 9
I1029 11:44:09.301415 31637504 process.cpp:2754] Returning '404 Not Found' for '/slave(926)/api/v1/executor'
W1029 11:44:09.303393 42475520 executor.cpp:672] Received '404 Not Found' (404 Not Found.) for UPDATE
I1029 11:44:09.610426 30027776 containerizer.cpp:3112] Container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 has exited
I1029 11:44:09.610886 30564352 provisioner.cpp:649] Ignoring destroy request for unknown container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822
I1029 11:44:09.610960 30027776 containerizer.cpp:2894] Checkpointing termination state to nested container's runtime directory '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Ope0XD/containers/f2ac6d81-c89d-4423-940e-84cee380b6d1/containers/d9fb3f61-e0b5-42ca-acd3-a95941c2b822/termination'
W1029 11:44:09.612341 27881472 composing.cpp:609] Attempted to destroy unknown container f2ac6d81-c89d-4423-940e-84cee380b6d1.c1ab30cc-50c7-4254-85c4-12440df81c28
I1029 11:44:09.612870 30027776 containerizer.cpp:2576] Destroying container f2ac6d81-c89d-4423-940e-84cee380b6d1 in RUNNING state
I1029 11:44:09.612921 30027776 containerizer.cpp:3273] Transitioning the state of container f2ac6d81-c89d-4423-940e-84cee380b6d1 from RUNNING to DESTROYING
I1029 11:44:09.613080 30027776 launcher.cpp:161] Asked to destroy container f2ac6d81-c89d-4423-940e-84cee380b6d1
I1029 11:44:09.616871 43548672 default_executor.cpp:959] Child container f2ac6d81-c89d-4423-940e-84cee380b6d1.d9fb3f61-e0b5-42ca-acd3-a95941c2b822 of task 'baea9a8f-c34f-491b-bd03-fa14f85bf3ab' completed in state TASK_KILLED: Command terminated with signal Terminated: 15
I1029 11:44:09.617053 43548672 default_executor.cpp:1081] Terminating after 1secs
I1029 11:44:09.617843 31637504 process.cpp:2754] Returning '404 Not Found' for '/slave(926)/api/v1/executor'
W1029 11:44:09.618432 45158400 executor.cpp:672] Received '404 Not Found' (404 Not Found.) for UPDATE
I1029 11:44:09.721683 29491200 containerizer.cpp:3112] Container f2ac6d81-c89d-4423-940e-84cee380b6d1 has exited
I1029 11:44:09.722615 27344896 provisioner.cpp:649] Ignoring destroy request for unknown container f2ac6d81-c89d-4423-940e-84cee380b6d1
I1029 11:44:09.728917 427447744 master.cpp:1135] Master terminating
I1029 11:44:09.729071 28954624 hierarchical.cpp:797] Removed all filters for agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
I1029 11:44:09.729089 28954624 hierarchical.cpp:672] Removed agent 76f01095-30e8-488f-993a-a2cd92ba3528-S0
[ FAILED ] MesosContainerizer/DefaultExecutorTest.MaxCompletionTime/0, where GetParam() = "mesos" (21981 ms)
{code}
> Test `DefaultExecutorTest.MaxCompletionTime` is flaky.
> ------------------------------------------------------
>
> Key: MESOS-9359
> URL: https://issues.apache.org/jira/browse/MESOS-9359
> Project: Mesos
> Issue Type: Bug
> Reporter: Meng Zhu
> Priority: Major
> Attachments: DefaultExecutorTest.MaxCompletionTime
>
>
> The test is flaky because its task with simple command `exit 0` took more than 2 seconds to complete (even though the container successfully transitioned to running state) which is not expected by the test, log snippets:
> {noformat}
> I1026 21:48:04.353044 30084 containerizer.cpp:1939] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"exit 0"},"environment":{"variables":[{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Owa0zJ/slaves/7f85af3e-a8b9-4252-b8f4-8d1a9bd0f1eb-S0/frameworks/7f85af3e-a8b9-4252-b8f4-8d1a9bd0f1eb-0000/executors/default/runs/72c8a5d0-1d90-4eff-8fc0-c690139c1664/containers/8e6b6380-ea9e-44f6-8abf-dd5bb6878739"},{"name":"MESOS_CONTAINER_IP","type":"VALUE","value":"172.16.10.21"}]},"task_environment":{},"user":"root","working_directory":"/tmp/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_Owa0zJ/slaves/7f85af3e-a8b9-4252-b8f4-8d1a9bd0f1eb-S0/frameworks/7f85af3e-a8b9-4252-b8f4-8d1a9bd0f1eb-0000/executors/default/runs/72c8a5d0-1d90-4eff-8fc0-c690139c1664/containers/8e6b6380-ea9e-44f6-8abf-dd5bb6878739"}" --pipe_read="99" --pipe_write="100" --runtime_directory="/tmp/MesosContainerizer_DefaultExecutorTest_MaxCompletionTime_0_g87RiO/containers/72c8a5d0-1d90-4eff-8fc0-c690139c1664/containers/8e6b6380-ea9e-44f6-8abf-dd5bb6878739" --unshare_namespace_mnt="false"'
> ...
> I1026 21:48:04.495669 30084 containerizer.cpp:3122] Transitioning the state of container 72c8a5d0-1d90-4eff-8fc0-c690139c1664.8e6b6380-ea9e-44f6-8abf-dd5bb6878739 from FETCHING to RUNNING
> ...
> II1026 21:48:06.506870 16743 default_executor.cpp:1293] Killing task 8a5fa021-1b4a-4bfc-a3df-848eb610a9f5 which exceeded its maximum completion time of 2secs
> I1026 21:48:06.529467 16743 default_executor.cpp:1126] Killing task 8a5fa021-1b4a-4bfc-a3df-848eb610a9f5 running in child container 72c8a5d0-1d90-4eff-8fc0-c690139c1664.8e6b6380-ea9e-44f6-8abf-dd5bb6878739 with SIGTERM signal
> {noformat}
> Full log attached.
--
This message was sent by Atlassian Jira
(v8.3.4#803005)