[MESOS-10022] ParentChildContainerTypeAndContentType/AgentContainerAPITest.NestedContainerLaunchFalse is flaky - ASF JIRA

XML

Word

Printable

JSON

Details

Type: Bug
Status: Open
Priority: Major
Resolution: Unresolved
Affects Version/s: None
Fix Version/s: None
Component/s: None
Labels:

Description

Observed in an internal CI.

[ RUN      ] ParentChildContainerTypeAndContentType/AgentContainerAPITest.NestedContainerLaunchFalse/6
I1029 12:39:34.366693 300656064 cluster.cpp:177] Creating default 'local' authorizer
I1029 12:39:34.368407 241573888 master.cpp:440] Master fbe7f7ab-3d14-43a7-94b9-19107afa59c4 (Jenkinss-Mac-mini.local) started on 10.0.49.4:53800
I1029 12:39:34.368432 241573888 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/Wpmw8n/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/Wpmw8n/master" --zk_session_timeout="10secs"
I1029 12:39:34.368610 241573888 master.cpp:492] Master only allowing authenticated frameworks to register
I1029 12:39:34.368623 241573888 master.cpp:498] Master only allowing authenticated agents to register
I1029 12:39:34.368630 241573888 master.cpp:504] Master only allowing authenticated HTTP frameworks to register
I1029 12:39:34.368639 241573888 credentials.hpp:37] Loading credentials for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/credentials'
I1029 12:39:34.368866 241573888 master.cpp:548] Using default 'crammd5' authenticator
I1029 12:39:34.368943 241573888 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1029 12:39:34.369016 241573888 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1029 12:39:34.369063 241573888 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1029 12:39:34.369107 241573888 master.cpp:629] Authorization enabled
I1029 12:39:34.369299 242647040 hierarchical.cpp:474] Initialized hierarchical allocator process
I1029 12:39:34.369338 242110464 whitelist_watcher.cpp:77] No whitelist given
I1029 12:39:34.371100 242647040 master.cpp:2170] Elected as the leading master!
I1029 12:39:34.371124 242647040 master.cpp:1666] Recovering from registrar
I1029 12:39:34.371201 242110464 registrar.cpp:339] Recovering registrar
I1029 12:39:34.371527 242110464 registrar.cpp:383] Successfully fetched the registry (0B) in 307200ns
I1029 12:39:34.371631 242110464 registrar.cpp:487] Applied 1 operations in 30737ns; attempting to update the registry
I1029 12:39:34.371907 243183616 registrar.cpp:544] Successfully updated the registry in 247040ns
I1029 12:39:34.371970 243183616 registrar.cpp:416] Successfully recovered registrar
I1029 12:39:34.372159 243720192 master.cpp:1819] Recovered 0 agents from the registry (163B); allowing 10mins for agents to reregister
I1029 12:39:34.372191 244793344 hierarchical.cpp:513] Skipping recovery of hierarchical allocator: nothing to recover
W1029 12:39:34.375097 300656064 process.cpp:2877] Attempted to spawn already running process files@10.0.49.4:53800
I1029 12:39:34.375782 300656064 containerizer.cpp:318] Using isolation { environment_secret, filesystem/posix, posix/mem, posix/cpu }
I1029 12:39:34.376047 300656064 provisioner.cpp:294] Using default backend 'copy'
I1029 12:39:34.376901 300656064 cluster.cpp:518] Creating default 'local' authorizer
I1029 12:39:34.377873 241037312 slave.cpp:267] Mesos agent started on (774)@10.0.49.4:53800
I1029 12:39:34.377907 241037312 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/woEIE7/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/Wpmw8n/woEIE7/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" --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/Wpmw8n/woEIE7/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --docker_volume_chown="false" --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/Wpmw8n/woEIE7/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/woEIE7/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/Wpmw8n/woEIE7/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/woEIE7/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/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_hfjeYC" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --version="false" --work_dir="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e" --zk_session_timeout="10secs"
I1029 12:39:34.378197 241037312 credentials.hpp:86] Loading credential for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/woEIE7/credential'
I1029 12:39:34.378335 241037312 slave.cpp:300] Agent using credential for: test-principal
I1029 12:39:34.378353 241037312 credentials.hpp:37] Loading credentials for authentication from '/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/Wpmw8n/woEIE7/http_credentials'
W1029 12:39:34.378348 300656064 process.cpp:2877] Attempted to spawn already running process version@10.0.49.4:53800
I1029 12:39:34.378612 241037312 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I1029 12:39:34.378664 241037312 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I1029 12:39:34.378767 241037312 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1029 12:39:34.378811 241037312 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I1029 12:39:34.378981 241037312 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1029 12:39:34.379032 241037312 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I1029 12:39:34.379312 241037312 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I1029 12:39:34.379717 300656064 sched.cpp:239] Version: 1.9.1
I1029 12:39:34.379658 241037312 slave.cpp:615] 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 12:39:34.379870 241037312 slave.cpp:623] Agent attributes: [  ]
I1029 12:39:34.379882 241037312 slave.cpp:632] Agent hostname: Jenkinss-Mac-mini.local
I1029 12:39:34.379933 242647040 task_status_update_manager.cpp:181] Pausing sending task status updates
I1029 12:39:34.380045 243183616 status_update_manager_process.hpp:379] Pausing operation status update manager
I1029 12:39:34.380089 244256768 sched.cpp:343] New master detected at master@10.0.49.4:53800
I1029 12:39:34.380127 244256768 sched.cpp:408] Authenticating with master master@10.0.49.4:53800
I1029 12:39:34.380139 244256768 sched.cpp:415] Using default CRAM-MD5 authenticatee
I1029 12:39:34.380370 244793344 authenticatee.cpp:121] Creating new client SASL connection
I1029 12:39:34.380580 243183616 master.cpp:10617] Authenticating scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800
I1029 12:39:34.380662 244256768 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1464)@10.0.49.4:53800
I1029 12:39:34.380766 242110464 state.cpp:67] Recovering state from '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta'
I1029 12:39:34.380785 241573888 authenticator.cpp:98] Creating new server SASL connection
I1029 12:39:34.380899 242647040 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1029 12:39:34.380924 242647040 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1029 12:39:34.380935 244793344 slave.cpp:7492] Finished recovering checkpointed state from '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta', beginning agent recovery
I1029 12:39:34.381067 241037312 authenticator.cpp:204] Received SASL authentication start
I1029 12:39:34.381163 241037312 authenticator.cpp:326] Authentication requires more steps
I1029 12:39:34.381168 243720192 task_status_update_manager.cpp:207] Recovering task status update manager
I1029 12:39:34.381230 244256768 authenticatee.cpp:259] Received SASL authentication step
I1029 12:39:34.381343 242110464 authenticator.cpp:232] Received SASL authentication step
I1029 12:39:34.381364 242110464 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 12:39:34.381378 242110464 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1029 12:39:34.381397 243183616 containerizer.cpp:821] Recovering Mesos containers
I1029 12:39:34.381417 242110464 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1029 12:39:34.381443 242110464 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 12:39:34.381456 242110464 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1029 12:39:34.381479 242110464 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1029 12:39:34.381500 242110464 authenticator.cpp:318] Authentication success
I1029 12:39:34.381556 243183616 containerizer.cpp:1161] Recovering isolators
I1029 12:39:34.381688 244256768 authenticatee.cpp:299] Authentication success
I1029 12:39:34.381714 243720192 master.cpp:10649] Successfully authenticated principal 'test-principal' at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800
I1029 12:39:34.381734 241573888 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1464)@10.0.49.4:53800
I1029 12:39:34.382028 242647040 sched.cpp:520] Successfully authenticated with master master@10.0.49.4:53800
I1029 12:39:34.382045 242647040 sched.cpp:835] Sending SUBSCRIBE call to master@10.0.49.4:53800
I1029 12:39:34.382128 242647040 sched.cpp:870] Will retry registration in 1.93847815secs if necessary
I1029 12:39:34.382257 243720192 containerizer.cpp:1200] Recovering provisioner
I1029 12:39:34.382277 242110464 master.cpp:2910] Received SUBSCRIBE call for framework 'default' at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800
I1029 12:39:34.382333 242110464 master.cpp:2242] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1029 12:39:34.382596 242647040 provisioner.cpp:518] Provisioner recovery complete
I1029 12:39:34.382617 244793344 master.cpp:2997] Subscribing framework default with checkpointing enabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
I1029 12:39:34.383085 244256768 composing.cpp:339] Finished recovering all containerizers
I1029 12:39:34.383373 242647040 slave.cpp:7973] Recovering executors
I1029 12:39:34.383486 242647040 slave.cpp:8126] Finished recovery
I1029 12:39:34.383756 244793344 master.cpp:10847] Adding framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800 with roles {  } suppressed
I1029 12:39:34.384013 241037312 sched.cpp:751] Framework registered with fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.384146 241037312 sched.cpp:770] Scheduler::registered took 111143ns
I1029 12:39:34.384255 241573888 hierarchical.cpp:605] Added framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.384393 241573888 hierarchical.cpp:1734] Performed allocation for 0 agents in 69449ns
I1029 12:39:34.384560 244793344 task_status_update_manager.cpp:181] Pausing sending task status updates
I1029 12:39:34.384562 243183616 slave.cpp:1351] New master detected at master@10.0.49.4:53800
I1029 12:39:34.384634 243183616 slave.cpp:1416] Detecting new master
I1029 12:39:34.384567 241037312 status_update_manager_process.hpp:379] Pausing operation status update manager
I1029 12:39:34.399978 244256768 slave.cpp:1443] Authenticating with master master@10.0.49.4:53800
I1029 12:39:34.400033 244256768 slave.cpp:1452] Using default CRAM-MD5 authenticatee
I1029 12:39:34.400149 241573888 authenticatee.cpp:121] Creating new client SASL connection
I1029 12:39:34.400336 244793344 master.cpp:10617] Authenticating slave(774)@10.0.49.4:53800
I1029 12:39:34.400419 241037312 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1465)@10.0.49.4:53800
I1029 12:39:34.400564 243183616 authenticator.cpp:98] Creating new server SASL connection
I1029 12:39:34.400665 242647040 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1029 12:39:34.400684 242647040 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1029 12:39:34.400772 242110464 authenticator.cpp:204] Received SASL authentication start
I1029 12:39:34.400872 242110464 authenticator.cpp:326] Authentication requires more steps
I1029 12:39:34.400923 244256768 authenticatee.cpp:259] Received SASL authentication step
I1029 12:39:34.401008 243720192 authenticator.cpp:232] Received SASL authentication step
I1029 12:39:34.401028 243720192 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 12:39:34.401041 243720192 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1029 12:39:34.401062 243720192 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1029 12:39:34.401075 243720192 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 12:39:34.401090 243720192 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1029 12:39:34.401099 243720192 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1029 12:39:34.401135 243720192 authenticator.cpp:318] Authentication success
I1029 12:39:34.401255 241573888 authenticatee.cpp:299] Authentication success
I1029 12:39:34.401281 241037312 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1465)@10.0.49.4:53800
I1029 12:39:34.401298 244793344 master.cpp:10649] Successfully authenticated principal 'test-principal' at slave(774)@10.0.49.4:53800
I1029 12:39:34.401499 243183616 slave.cpp:1543] Successfully authenticated with master master@10.0.49.4:53800
I1029 12:39:34.401789 243183616 slave.cpp:1993] Will retry registration in 5.124412ms if necessary
I1029 12:39:34.401830 243720192 master.cpp:7088] Received register agent message from slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:34.401947 243720192 master.cpp:4204] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I1029 12:39:34.402216 244256768 master.cpp:7155] Authorized registration of agent at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:34.402269 244256768 master.cpp:7267] Registering agent at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) with id fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
I1029 12:39:34.402593 241037312 registrar.cpp:487] Applied 1 operations in 166888ns; attempting to update the registry
I1029 12:39:34.402912 242647040 registrar.cpp:544] Successfully updated the registry in 262144ns
I1029 12:39:34.402982 242110464 master.cpp:7315] Admitted agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:34.403303 243183616 slave.cpp:1576] Registered with master master@10.0.49.4:53800; given agent ID fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
I1029 12:39:34.403273 242110464 master.cpp:7360] Registered agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1029 12:39:34.403328 241037312 hierarchical.cpp:858] Added agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 (Jenkinss-Mac-mini.local) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1029 12:39:34.403362 243720192 task_status_update_manager.cpp:188] Resuming sending task status updates
I1029 12:39:34.403689 241037312 hierarchical.cpp:1734] Performed allocation for 1 agents in 264663ns
I1029 12:39:34.403836 243183616 slave.cpp:1611] Checkpointing SlaveInfo to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/slave.info'
I1029 12:39:34.403852 242647040 status_update_manager_process.hpp:385] Resuming operation status update manager
I1029 12:39:34.403880 241573888 master.cpp:10432] Sending offers [ fbe7f7ab-3d14-43a7-94b9-19107afa59c4-O0 ] to framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800
I1029 12:39:34.404155 244256768 sched.cpp:934] Scheduler::resourceOffers took 67335ns
I1029 12:39:34.404701 243183616 slave.cpp:1663] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"lkWo5grDR0G71FLlWugFpA=="},"slave_id":{"value":"fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0"},"update_oversubscribed_resources":false}
I1029 12:39:34.404978 244256768 master.cpp:12724] Removing offer fbe7f7ab-3d14-43a7-94b9-19107afa59c4-O0
I1029 12:39:34.405261 244256768 master.cpp:4741] Processing ACCEPT call for offers: [ fbe7f7ab-3d14-43a7-94b9-19107afa59c4-O0 ] on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) for framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800
I1029 12:39:34.405342 244256768 master.cpp:3758] Authorizing framework principal 'test-principal' to launch task 79ee2719-89fd-4f59-a207-6ef2e988a196
I1029 12:39:34.405797 244256768 master.cpp:8487] Ignoring update on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) as it reports no changes
I1029 12:39:34.406535 244256768 master.cpp:4302] Adding task 79ee2719-89fd-4f59-a207-6ef2e988a196 with resources ports(allocated: *):[31000-32000]; cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800 on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:34.406781 244256768 master.cpp:5720] Launching task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800 with resources [{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"},{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"}] on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) on  new executor
I1029 12:39:34.407104 243183616 hierarchical.cpp:1658] Allocation paused
I1029 12:39:34.407130 243183616 hierarchical.cpp:1668] Allocation resumed
I1029 12:39:34.407711 242647040 slave.cpp:2130] Got assigned task '79ee2719-89fd-4f59-a207-6ef2e988a196' for framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.407822 242647040 slave.cpp:9917] Checkpointing FrameworkInfo to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/framework.info'
I1029 12:39:34.408524 242647040 slave.cpp:9928] Checkpointing framework pid 'scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800' to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/framework.pid'
I1029 12:39:34.409543 242647040 slave.cpp:2504] Authorizing task '79ee2719-89fd-4f59-a207-6ef2e988a196' for framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.409584 242647040 slave.cpp:9465] Authorizing framework principal 'test-principal' to launch task 79ee2719-89fd-4f59-a207-6ef2e988a196
I1029 12:39:34.410553 242647040 slave.cpp:2977] Launching task '79ee2719-89fd-4f59-a207-6ef2e988a196' for framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.410619 242647040 paths.cpp:817] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705' for user 'jenkins'
I1029 12:39:34.412082 242647040 slave.cpp:10715] Checkpointing ExecutorInfo to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/executor.info'
I1029 12:39:34.412827 242647040 paths.cpp:820] Creating sandbox '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705'
I1029 12:39:34.413305 242647040 slave.cpp:10003] Launching executor '79ee2719-89fd-4f59-a207-6ef2e988a196' of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705'
I1029 12:39:34.413833 242647040 slave.cpp:10746] Checkpointing TaskInfo to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705/tasks/79ee2719-89fd-4f59-a207-6ef2e988a196/task.info'
I1029 12:39:34.414625 242647040 slave.cpp:3209] Queued task '79ee2719-89fd-4f59-a207-6ef2e988a196' for executor '79ee2719-89fd-4f59-a207-6ef2e988a196' of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.415045 242647040 slave.cpp:1084] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705' to virtual path '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/latest'
I1029 12:39:34.415076 242647040 slave.cpp:1084] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705' to virtual path '/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/latest'
I1029 12:39:34.415091 242647040 slave.cpp:1084] Successfully attached '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705' to virtual path '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705'
I1029 12:39:34.415323 242647040 slave.cpp:3657] Launching container 294f2461-ebc7-4388-98d7-30acbf533705 for executor '79ee2719-89fd-4f59-a207-6ef2e988a196' of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:34.415545 244793344 containerizer.cpp:1396] Starting container 294f2461-ebc7-4388-98d7-30acbf533705
I1029 12:39:34.415957 244793344 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from STARTING to PROVISIONING after 120832ns
I1029 12:39:34.416674 244793344 containerizer.cpp:1574] Checkpointed ContainerConfig at '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_hfjeYC/containers/294f2461-ebc7-4388-98d7-30acbf533705/config'
I1029 12:39:34.416694 244793344 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from PROVISIONING to PREPARING after 737024ns
I1029 12:39:34.418731 243183616 containerizer.cpp:2100] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-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-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"10.0.49.4:53800"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiIyOTRmMjQ2MS1lYmM3LTQzODgtOThkNy0zMGFjYmY1MzM3MDUiLCJlaWQiOiI3OWVlMjcxOS04OWZkLTRmNTktYTIwNy02ZWYyZTk4OGExOTYiLCJmaWQiOiJmYmU3ZjdhYi0zZDE0LTQzYTctOTRiOS0xOTEwN2FmYTU5YzQtMDAwMCJ9.T3O2IiZnHp-P4Kr_YoRlFmA2TD0MM4sT8xdAo7q02lE"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"79ee2719-89fd-4f59-a207-6ef2e988a196"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(774)@10.0.49.4:53800"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"2secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705"}]},"task_environment":{},"user":"jenkins","working_directory":"/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705"}" --pipe_read="24" --pipe_write="28" --runtime_directory="/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_hfjeYC/containers/294f2461-ebc7-4388-98d7-30acbf533705"'
I1029 12:39:34.420681 243183616 launcher.cpp:145] Forked child with pid '32082' for container '294f2461-ebc7-4388-98d7-30acbf533705'
I1029 12:39:34.421079 243183616 containerizer.cpp:2209] Checkpointing container's forked pid 32082 to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705/pids/forked.pid'
I1029 12:39:34.422943 243183616 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from PREPARING to ISOLATING after 6.051072ms
I1029 12:39:34.424793 243183616 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from ISOLATING to FETCHING after 2048us
I1029 12:39:34.424913 242110464 fetcher.cpp:369] Starting to fetch URIs for container: 294f2461-ebc7-4388-98d7-30acbf533705, directory: /var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705
I1029 12:39:34.425896 244256768 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from FETCHING to RUNNING after 1.100032ms
I1029 12:39:55.005030 242110464 hierarchical.cpp:1734] Performed allocation for 1 agents in 178504ns
[warn] kq_init: detected broken kqueue; not using.: Undefined error: 0
I1029 12:39:55.107791 167579648 exec.cpp:164] Version: 1.9.1
I1029 12:39:55.111353 244793344 slave.cpp:5256] Got registration for executor '79ee2719-89fd-4f59-a207-6ef2e988a196' of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 from executor(1)@10.0.49.4:56392
I1029 12:39:55.111716 244793344 slave.cpp:5342] Checkpointing executor pid 'executor(1)@10.0.49.4:56392' to '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/ParentChildContainerTypeAndContentType_AgentContainerAPITest_NestedContainerLaunchFalse_6_zkzx8e/meta/slaves/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0/frameworks/fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000/executors/79ee2719-89fd-4f59-a207-6ef2e988a196/runs/294f2461-ebc7-4388-98d7-30acbf533705/pids/libprocess.pid'
I1029 12:39:56.005987 241573888 hierarchical.cpp:1734] Performed allocation for 1 agents in 85720ns
I1029 12:39:57.007875 243720192 hierarchical.cpp:1734] Performed allocation for 1 agents in 123595ns
I1029 12:39:58.015012 243183616 hierarchical.cpp:1734] Performed allocation for 1 agents in 86309ns
I1029 12:39:58.279148 168652800 exec.cpp:237] Executor registered on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
I1029 12:39:58.280709 244256768 slave.cpp:3427] Sending queued task '79ee2719-89fd-4f59-a207-6ef2e988a196' to executor '79ee2719-89fd-4f59-a207-6ef2e988a196' of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 at executor(1)@10.0.49.4:56392
I1029 12:39:58.284301 168116224 executor.cpp:190] Received SUBSCRIBED event
I1029 12:39:58.285012 168116224 executor.cpp:194] Subscribed executor on Jenkinss-Mac-mini.local
I1029 12:39:58.285229 168116224 executor.cpp:190] Received LAUNCH event
I1029 12:39:58.289726 168116224 executor.cpp:722] Starting task 79ee2719-89fd-4f59-a207-6ef2e988a196
I1029 12:39:58.291127 242110464 slave.cpp:5737] Handling status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 from executor(1)@10.0.49.4:56392
I1029 12:39:58.293874 244793344 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.294005 244793344 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.294837 244793344 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.295039 244793344 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 to the agent
I1029 12:39:58.295389 243183616 slave.cpp:6277] Forwarding the update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 to master@10.0.49.4:53800
I1029 12:39:58.295492 243183616 slave.cpp:6161] Task status update manager successfully handled status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.295545 243183616 slave.cpp:6188] Sending acknowledgement for status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 to executor(1)@10.0.49.4:56392
I1029 12:39:58.296183 241037312 master.cpp:8985] Status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 from agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:58.296264 241037312 master.cpp:9042] Forwarding status update TASK_STARTING (Status UUID: da66cf70-f294-417e-b7e8-a1fa794d5a27) for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.296444 241037312 master.cpp:12073] Updating the state of task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1029 12:39:58.297475 242647040 sched.cpp:1042] Scheduler::statusUpdate took 134260ns
I1029 12:39:58.298105 243720192 master.cpp:6695] Processing ACKNOWLEDGE call for status da66cf70-f294-417e-b7e8-a1fa794d5a27 for task 79ee2719-89fd-4f59-a207-6ef2e988a196 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 (default) at scheduler-c92ca22a-7e6b-4dc3-b4ef-fe6ff3617225@10.0.49.4:53800 on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
../../src/tests/agent_container_api_tests.cpp:697: Failure
parentContainerId: Failed to launch parent container
I1029 12:39:58.299089 300656064 slave.cpp:924] Agent terminating
I1029 12:39:58.300963 244793344 master.cpp:1297] Agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) disconnected
I1029 12:39:58.301009 244793344 master.cpp:3399] Disconnecting agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:58.301055 244793344 master.cpp:3418] Deactivating agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local)
I1029 12:39:58.301307 244793344 hierarchical.cpp:1047] Agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 deactivated
I1029 12:39:58.302247 241037312 containerizer.cpp:2620] Destroying container 294f2461-ebc7-4388-98d7-30acbf533705 in RUNNING state
I1029 12:39:58.302294 241037312 containerizer.cpp:3318] Transitioning the state of container 294f2461-ebc7-4388-98d7-30acbf533705 from RUNNING to DESTROYING after 23.876399872secs
I1029 12:39:58.302465 241037312 launcher.cpp:161] Asked to destroy container 294f2461-ebc7-4388-98d7-30acbf533705
I1029 12:39:58.304868 168116224 executor.cpp:740] Forked command at 32110
I1029 12:39:58.313320 245329920 process.cpp:2678] Failed to deliver libprocess message to /slave(774)/mesos.internal.StatusUpdateMessage
I1029 12:39:58.379806 242647040 containerizer.cpp:3156] Container 294f2461-ebc7-4388-98d7-30acbf533705 has exited
I1029 12:39:58.380657 242647040 provisioner.cpp:652] Ignoring destroy request for unknown container 294f2461-ebc7-4388-98d7-30acbf533705
I1029 12:39:58.385525 300656064 master.cpp:1137] Master terminating
W1029 12:39:58.385627 300656064 master.cpp:12157] Removing task 79ee2719-89fd-4f59-a207-6ef2e988a196 with resources ports(allocated: *):[31000-32000]; cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024 of framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000 on agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0 at slave(774)@10.0.49.4:53800 (Jenkinss-Mac-mini.local) in non-terminal state TASK_STARTING
I1029 12:39:58.385880 241573888 hierarchical.cpp:1023] Removed all filters for agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
I1029 12:39:58.385895 241573888 hierarchical.cpp:900] Removed agent fbe7f7ab-3d14-43a7-94b9-19107afa59c4-S0
I1029 12:39:58.386473 241573888 hierarchical.cpp:655] Removed framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
I1029 12:39:58.388495 300656064 sched.cpp:2166] Asked to stop the driver
I1029 12:39:58.388556 242110464 sched.cpp:1204] Stopping framework fbe7f7ab-3d14-43a7-94b9-19107afa59c4-0000
[  FAILED  ] ParentChildContainerTypeAndContentType/AgentContainerAPITest.NestedContainerLaunchFalse/6, where GetParam() = (0, 1, application/x-protobuf, ("posix/cpu,posix/mem", "posix", "")) (24025 ms)

Attachments

Activity

People

Assignee:: Unassigned

Reporter:: Andrei Sekretenko

Votes:: 0 Vote for this issue

Watchers:: 1 Start watching this issue

Dates

Created:: 30/Oct/19 12:46

Updated:: 30/Oct/19 12:46