See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3033/changes>
Changes: [anand] Fixed erroneous usage of 'Accept' header as 'Accept-Type'. [vinodkone] Removed "enable_io_switchboard_server" agent flag. [vinodkone] Fixed EOF bug when tty is enabled in switch board server. [yujie.jay] Updated address of 'http::connect()' with http scheme for SSL support. [yujie.jay] Changed 'Socket::create()' in IOSwitchboard to always be of 'kind' POLL. [yujie.jay] Changed the timing of entering mount namespace for DEBUG container. [yujie.jay] Fixed an unreachable statement in launch.cpp. [vinodkone] Added a `http_heartbeat_interval` flag to the agent flags. [vinodkone] Added a bi-directional heartbeat for IOSwitchboard connections. ------------------------------------------ [...truncated 152024 lines...] I1213 00:04:55.900219 28181 provisioner.cpp:324] Ignoring destroy request for unknown container 336ee787-f6a4-4135-bac6-6a93f062401f I1213 00:04:55.901373 28181 slave.cpp:4676] Executor 'bbfe2eac-df4d-409f-a29d-e69de03e112e' of framework 7ca05b53-9260-4304-82b3-aa01668044c6-0000 terminated with signal Killed I1213 00:04:55.901471 28181 slave.cpp:4780] Cleaning up executor 'bbfe2eac-df4d-409f-a29d-e69de03e112e' of framework 7ca05b53-9260-4304-82b3-aa01668044c6-0000 at executor(1)@172.17.0.3:37672 I1213 00:04:55.901798 28172 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_UA65op/slaves/7ca05b53-9260-4304-82b3-aa01668044c6-S0/frameworks/7ca05b53-9260-4304-82b3-aa01668044c6-0000/executors/bbfe2eac-df4d-409f-a29d-e69de03e112e/runs/336ee787-f6a4-4135-bac6-6a93f062401f' for gc 6.99998956391111days in the future I1213 00:04:55.901955 28181 slave.cpp:4868] Cleaning up framework 7ca05b53-9260-4304-82b3-aa01668044c6-0000 I1213 00:04:55.901996 28172 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_UA65op/slaves/7ca05b53-9260-4304-82b3-aa01668044c6-S0/frameworks/7ca05b53-9260-4304-82b3-aa01668044c6-0000/executors/bbfe2eac-df4d-409f-a29d-e69de03e112e' for gc 6.99998956145778days in the future I1213 00:04:55.902156 28184 status_update_manager.cpp:285] Closing status update streams for framework 7ca05b53-9260-4304-82b3-aa01668044c6-0000 I1213 00:04:55.902220 28184 status_update_manager.cpp:531] Cleaning up status update stream for task bbfe2eac-df4d-409f-a29d-e69de03e112e of framework 7ca05b53-9260-4304-82b3-aa01668044c6-0000 I1213 00:04:55.902254 28178 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_UA65op/slaves/7ca05b53-9260-4304-82b3-aa01668044c6-S0/frameworks/7ca05b53-9260-4304-82b3-aa01668044c6-0000' for gc 6.99998955878519days in the future I1213 00:04:55.902251 28181 slave.cpp:791] Agent terminating I1213 00:04:55.902514 28175 master.cpp:1258] Agent 7ca05b53-9260-4304-82b3-aa01668044c6-S0 at slave(578)@172.17.0.3:35837 (be9fb70992f0) disconnected I1213 00:04:55.902544 28175 master.cpp:2977] Disconnecting agent 7ca05b53-9260-4304-82b3-aa01668044c6-S0 at slave(578)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:55.902604 28175 master.cpp:2996] Deactivating agent 7ca05b53-9260-4304-82b3-aa01668044c6-S0 at slave(578)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:55.902858 28174 hierarchical.cpp:589] Agent 7ca05b53-9260-4304-82b3-aa01668044c6-S0 deactivated I1213 00:04:55.910665 28171 master.cpp:1097] Master terminating I1213 00:04:55.911828 28185 hierarchical.cpp:522] Removed agent 7ca05b53-9260-4304-82b3-aa01668044c6-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0 (611 ms) [ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 I1213 00:04:55.923773 28153 cluster.cpp:160] Creating default 'local' authorizer I1213 00:04:55.927094 28176 master.cpp:380] Master 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36 (be9fb70992f0) started on 172.17.0.3:35837 I1213 00:04:55.927180 28176 master.cpp:382] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/wWDZGu/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/wWDZGu/master" --zk_session_timeout="10secs" I1213 00:04:55.927721 28176 master.cpp:432] Master only allowing authenticated frameworks to register I1213 00:04:55.927749 28176 master.cpp:446] Master only allowing authenticated agents to register I1213 00:04:55.927768 28176 master.cpp:459] Master only allowing authenticated HTTP frameworks to register I1213 00:04:55.927788 28176 credentials.hpp:37] Loading credentials for authentication from '/tmp/wWDZGu/credentials' I1213 00:04:55.928177 28176 master.cpp:504] Using default 'crammd5' authenticator I1213 00:04:55.928346 28176 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1213 00:04:55.928509 28176 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1213 00:04:55.929312 28176 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1213 00:04:55.929577 28176 master.cpp:584] Authorization enabled I1213 00:04:55.929939 28174 hierarchical.cpp:149] Initialized hierarchical allocator process I1213 00:04:55.930039 28182 whitelist_watcher.cpp:77] No whitelist given I1213 00:04:55.933245 28173 master.cpp:2045] Elected as the leading master! I1213 00:04:55.933281 28173 master.cpp:1568] Recovering from registrar I1213 00:04:55.933488 28172 registrar.cpp:329] Recovering registrar I1213 00:04:55.934340 28172 registrar.cpp:362] Successfully fetched the registry (0B) in 802816ns I1213 00:04:55.934454 28172 registrar.cpp:461] Applied 1 operations in 32493ns; attempting to update the registry I1213 00:04:55.935425 28182 registrar.cpp:506] Successfully updated the registry in 897024ns I1213 00:04:55.935595 28182 registrar.cpp:392] Successfully recovered registrar I1213 00:04:55.936086 28176 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1213 00:04:55.936117 28178 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover I1213 00:04:55.939565 28153 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W1213 00:04:55.940214 28153 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos W1213 00:04:55.940408 28153 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I1213 00:04:55.944727 28153 cluster.cpp:446] Creating default 'local' authorizer I1213 00:04:55.947279 28176 slave.cpp:209] Mesos agent started on (579)@172.17.0.3:35837 I1213 00:04:55.947312 28176 slave.cpp:210] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI" I1213 00:04:55.947751 28176 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/credential' I1213 00:04:55.947888 28176 slave.cpp:347] Agent using credential for: test-principal I1213 00:04:55.947911 28176 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/http_credentials' I1213 00:04:55.948264 28176 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1213 00:04:55.948400 28176 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1213 00:04:55.948683 28153 sched.cpp:232] Version: 1.2.0 I1213 00:04:55.949405 28185 sched.cpp:336] New master detected at [email protected]:35837 I1213 00:04:55.949476 28185 sched.cpp:402] Authenticating with master [email protected]:35837 I1213 00:04:55.949496 28185 sched.cpp:409] Using default CRAM-MD5 authenticatee I1213 00:04:55.949812 28180 authenticatee.cpp:121] Creating new client SASL connection I1213 00:04:55.949795 28176 slave.cpp:534] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1213 00:04:55.949908 28176 slave.cpp:542] Agent attributes: [ ] I1213 00:04:55.949929 28176 slave.cpp:547] Agent hostname: be9fb70992f0 I1213 00:04:55.950083 28171 master.cpp:6748] Authenticating [email protected]:35837 I1213 00:04:55.950999 28177 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1131)@172.17.0.3:35837 I1213 00:04:55.951294 28179 authenticator.cpp:98] Creating new server SASL connection I1213 00:04:55.951705 28183 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1213 00:04:55.951753 28183 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1213 00:04:55.951896 28180 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/meta' I1213 00:04:55.951997 28175 authenticator.cpp:204] Received SASL authentication start I1213 00:04:55.952110 28175 authenticator.cpp:326] Authentication requires more steps I1213 00:04:55.952227 28178 status_update_manager.cpp:203] Recovering status update manager I1213 00:04:55.952271 28175 authenticatee.cpp:259] Received SASL authentication step I1213 00:04:55.952451 28177 authenticator.cpp:232] Received SASL authentication step I1213 00:04:55.952560 28177 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'be9fb70992f0' server FQDN: 'be9fb70992f0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1213 00:04:55.952565 28173 containerizer.cpp:594] Recovering containerizer I1213 00:04:55.952584 28177 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1213 00:04:55.952616 28177 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1213 00:04:55.952649 28177 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'be9fb70992f0' server FQDN: 'be9fb70992f0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1213 00:04:55.952667 28177 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1213 00:04:55.952683 28177 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1213 00:04:55.952711 28177 authenticator.cpp:318] Authentication success I1213 00:04:55.952802 28178 authenticatee.cpp:299] Authentication success I1213 00:04:55.952956 28183 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1131)@172.17.0.3:35837 I1213 00:04:55.953019 28176 master.cpp:6778] Successfully authenticated principal 'test-principal' at [email protected]:35837 I1213 00:04:55.953495 28186 sched.cpp:508] Successfully authenticated with master [email protected]:35837 I1213 00:04:55.953527 28186 sched.cpp:826] Sending SUBSCRIBE call to [email protected]:35837 I1213 00:04:55.953647 28186 sched.cpp:859] Will retry registration in 1.743402228secs if necessary I1213 00:04:55.953820 28179 master.cpp:2633] Received SUBSCRIBE call for framework 'default' at [email protected]:35837 I1213 00:04:55.953863 28179 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role '*' I1213 00:04:55.954396 28186 master.cpp:2709] Subscribing framework default with checkpointing disabled and capabilities [ ] I1213 00:04:55.954488 28173 provisioner.cpp:253] Provisioner recovery complete I1213 00:04:55.955003 28177 slave.cpp:5415] Finished recovery I1213 00:04:55.955334 28178 sched.cpp:749] Framework registered with 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.955392 28178 sched.cpp:763] Scheduler::registered took 34111ns I1213 00:04:55.955428 28172 hierarchical.cpp:276] Added framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.955513 28172 hierarchical.cpp:1689] No allocations performed I1213 00:04:55.955549 28172 hierarchical.cpp:1784] No inverse offers to send out! I1213 00:04:55.955608 28172 hierarchical.cpp:1291] Performed allocation for 0 agents in 165921ns I1213 00:04:55.955638 28177 slave.cpp:5589] Querying resource estimator for oversubscribable resources I1213 00:04:55.955987 28177 status_update_manager.cpp:177] Pausing sending status updates I1213 00:04:55.956018 28184 slave.cpp:919] New master detected at [email protected]:35837 I1213 00:04:55.956065 28184 slave.cpp:978] Authenticating with master [email protected]:35837 I1213 00:04:55.956137 28184 slave.cpp:989] Using default CRAM-MD5 authenticatee I1213 00:04:55.956305 28184 slave.cpp:951] Detecting new master I1213 00:04:55.956400 28174 authenticatee.cpp:121] Creating new client SASL connection I1213 00:04:55.956507 28184 slave.cpp:5603] Received oversubscribable resources {} from the resource estimator I1213 00:04:55.956682 28186 master.cpp:6748] Authenticating slave(579)@172.17.0.3:35837 I1213 00:04:55.956810 28179 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1132)@172.17.0.3:35837 I1213 00:04:55.957113 28181 authenticator.cpp:98] Creating new server SASL connection I1213 00:04:55.957340 28183 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1213 00:04:55.957389 28183 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1213 00:04:55.957516 28184 authenticator.cpp:204] Received SASL authentication start I1213 00:04:55.957582 28184 authenticator.cpp:326] Authentication requires more steps I1213 00:04:55.957778 28183 authenticatee.cpp:259] Received SASL authentication step I1213 00:04:55.957921 28172 authenticator.cpp:232] Received SASL authentication step I1213 00:04:55.957959 28172 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'be9fb70992f0' server FQDN: 'be9fb70992f0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1213 00:04:55.957988 28172 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1213 00:04:55.958048 28172 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1213 00:04:55.958101 28172 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'be9fb70992f0' server FQDN: 'be9fb70992f0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1213 00:04:55.958133 28172 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1213 00:04:55.958160 28172 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1213 00:04:55.958197 28172 authenticator.cpp:318] Authentication success I1213 00:04:55.958335 28176 authenticatee.cpp:299] Authentication success I1213 00:04:55.958370 28180 master.cpp:6778] Successfully authenticated principal 'test-principal' at slave(579)@172.17.0.3:35837 I1213 00:04:55.958544 28186 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1132)@172.17.0.3:35837 I1213 00:04:55.958837 28179 slave.cpp:1073] Successfully authenticated with master [email protected]:35837 I1213 00:04:55.959059 28179 slave.cpp:1487] Will retry registration in 17.832697ms if necessary I1213 00:04:55.959290 28175 master.cpp:5161] Registering agent at slave(579)@172.17.0.3:35837 (be9fb70992f0) with id 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 I1213 00:04:55.959700 28182 registrar.cpp:461] Applied 1 operations in 82079ns; attempting to update the registry I1213 00:04:55.961444 28182 registrar.cpp:506] Successfully updated the registry in 1.665024ms I1213 00:04:55.962239 28177 slave.cpp:4267] Received ping from slave-observer(533)@172.17.0.3:35837 I1213 00:04:55.962378 28173 master.cpp:5232] Registered agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1213 00:04:55.962481 28177 slave.cpp:1119] Registered with master [email protected]:35837; given agent ID 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 I1213 00:04:55.962515 28177 fetcher.cpp:90] Clearing fetcher cache I1213 00:04:55.962700 28174 status_update_manager.cpp:184] Resuming sending status updates I1213 00:04:55.962929 28180 hierarchical.cpp:490] Added agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 (be9fb70992f0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I1213 00:04:55.963023 28177 slave.cpp:1142] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/meta/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/slave.info' I1213 00:04:55.963448 28177 slave.cpp:1179] Forwarding total oversubscribed resources {} I1213 00:04:55.963619 28177 master.cpp:5633] Received update of agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) with total oversubscribed resources {} I1213 00:04:55.964100 28180 hierarchical.cpp:1784] No inverse offers to send out! I1213 00:04:55.964179 28180 hierarchical.cpp:1314] Performed allocation for agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 in 1.214589ms I1213 00:04:55.964357 28180 hierarchical.cpp:560] Agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 (be9fb70992f0) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) I1213 00:04:55.964529 28180 hierarchical.cpp:1689] No allocations performed I1213 00:04:55.964577 28180 hierarchical.cpp:1784] No inverse offers to send out! I1213 00:04:55.964599 28175 master.cpp:6577] Sending 1 offers to framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 I1213 00:04:55.964649 28180 hierarchical.cpp:1314] Performed allocation for agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 in 245581ns I1213 00:04:55.965116 28184 sched.cpp:923] Scheduler::resourceOffers took 136185ns I1213 00:04:55.967095 28181 master.cpp:3588] Processing ACCEPT call for offers: [ 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-O0 ] on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) for framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 I1213 00:04:55.967223 28181 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task a726ec84-49d4-4497-ae61-a774ce6f3070 I1213 00:04:55.969617 28181 master.cpp:8501] Adding task a726ec84-49d4-4497-ae61-a774ce6f3070 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 (be9fb70992f0) I1213 00:04:55.969998 28181 master.cpp:4240] Launching task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:55.970895 28183 slave.cpp:1551] Got assigned task 'a726ec84-49d4-4497-ae61-a774ce6f3070' for framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.971699 28183 slave.cpp:1713] Launching task 'a726ec84-49d4-4497-ae61-a774ce6f3070' for framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.972512 28183 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a' to user 'mesos' I1213 00:04:55.980967 28183 slave.cpp:6342] Launching executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a' I1213 00:04:55.981811 28172 containerizer.cpp:986] Starting container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a for executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.981964 28183 slave.cpp:2035] Queued task 'a726ec84-49d4-4497-ae61-a774ce6f3070' for executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:55.982125 28183 slave.cpp:872] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a' I1213 00:04:55.987428 28186 containerizer.cpp:1522] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.2.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.2.0\/_build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.3:35837"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI\/slaves\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0\/frameworks\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000\/executors\/a726ec84-49d4-4497-ae61-a774ce6f3070\/runs\/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a"},{"name":"MESOS_EXECUTOR_ID","value":"a726ec84-49d4-4497-ae61-a774ce6f3070"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(579)@172.17.0.3:35837"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI\/slaves\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0\/frameworks\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000\/executors\/a726ec84-49d4-4497-ae61-a774ce6f3070\/runs\/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI\/slaves\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0\/frameworks\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000\/executors\/a726ec84-49d4-4497-ae61-a774ce6f3070\/runs\/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI\/slaves\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0\/frameworks\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000\/executors\/a726ec84-49d4-4497-ae61-a774ce6f3070\/runs\/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI\/slaves\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0\/frameworks\/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000\/executors\/a726ec84-49d4-4497-ae61-a774ce6f3070\/runs\/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/containers/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a" --unshare_namespace_mnt="false"' I1213 00:04:55.991145 28186 launcher.cpp:133] Forked child with pid '8807' for container '4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a' I1213 00:04:55.994981 28177 fetcher.cpp:349] Starting to fetch URIs for container: 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a I1213 00:04:56.185428 28180 slave.cpp:3309] Got registration for executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 from executor(1)@172.17.0.3:54056 I1213 00:04:56.188045 28185 slave.cpp:2251] Sending queued task 'a726ec84-49d4-4497-ae61-a774ce6f3070' to executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 at executor(1)@172.17.0.3:54056 I1213 00:04:56.205934 28186 slave.cpp:3744] Handling status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 from executor(1)@172.17.0.3:54056 I1213 00:04:56.207715 28185 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.207803 28185 status_update_manager.cpp:500] Creating StatusUpdate stream for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.208397 28185 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 to the agent I1213 00:04:56.208794 28184 slave.cpp:4185] Forwarding the update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 to [email protected]:35837 I1213 00:04:56.209081 28184 slave.cpp:4079] Status update manager successfully handled status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.209146 28184 slave.cpp:4095] Sending acknowledgement for status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 to executor(1)@172.17.0.3:54056 I1213 00:04:56.209255 28178 master.cpp:5769] Status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 from agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:56.209316 28178 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.209537 28178 master.cpp:7867] Updating the state of task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1213 00:04:56.210057 28177 sched.cpp:1031] Scheduler::statusUpdate took 360011ns I1213 00:04:56.210420 28178 master.cpp:4877] Processing ACKNOWLEDGE call ad99ac58-75f4-4c09-b140-8014af099719 for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 I1213 00:04:56.210710 28182 status_update_manager.cpp:395] Received status update acknowledgement (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.211129 28181 slave.cpp:3026] Status update manager successfully handled status update acknowledgement (UUID: ad99ac58-75f4-4c09-b140-8014af099719) for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.214854 28181 process.cpp:3679] Handling HTTP event for process 'slave(579)' with path: '/slave(579)/api/v1' I1213 00:04:56.216275 28186 http.cpp:301] HTTP POST for /slave(579)/api/v1 from 172.17.0.3:40850 I1213 00:04:56.217036 28186 http.cpp:462] Processing call LAUNCH_NESTED_CONTAINER_SESSION I1213 00:04:56.217701 28174 containerizer.cpp:1789] Starting nested container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.218057 28174 containerizer.cpp:1813] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a/containers/8aefad1c-5c65-42c8-bdb2-245f81ba76eb' to user 'mesos' I1213 00:04:56.229229 28172 switchboard.cpp:567] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-2ea745f0-d929-47dc-ba0e-ff103e67bb36" --stderr_from_fd="61" --stderr_to_fd="2" --stdin_to_fd="29" --stdout_from_fd="41" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.233409 28172 switchboard.cpp:597] Created I/O switchboard server (pid: 8868) listening on socket file '/tmp/mesos-io-switchboard-2ea745f0-d929-47dc-ba0e-ff103e67bb36' for container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.235498 28184 containerizer.cpp:1522] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{},"err":{"fd":62,"type":"FD"},"in":{"fd":28,"type":"FD"},"out":{"fd":42,"type":"FD"},"user":"mesos"}" --pipe_read="29" --pipe_write="41" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/containers/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a/containers/8aefad1c-5c65-42c8-bdb2-245f81ba76eb" --unshare_namespace_mnt="false"' I1213 00:04:56.238628 28184 launcher.cpp:133] Forked child with pid '8869' for container '4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb' I1213 00:04:56.242108 28182 fetcher.cpp:349] Starting to fetch URIs for container: 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a/containers/8aefad1c-5c65-42c8-bdb2-245f81ba76eb **** DEADLOCK DETECTED! **** You are waiting on process __limiter__(597)@172.17.0.3:35837 that it is currently executing. W1213 00:04:56.343981 28182 http.cpp:2611] Launch nested container session connection for container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb closed I1213 00:04:56.344203 28183 containerizer.cpp:2100] Destroying container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb in RUNNING state I1213 00:04:56.344450 28183 launcher.cpp:149] Asked to destroy container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.356573 28185 http.cpp:2596] Received EOF attach response for 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.400990 28184 switchboard.cpp:837] I/O switchboard server process for container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb has terminated (status=0) I1213 00:04:56.401628 28178 containerizer.cpp:2463] Container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb has exited I1213 00:04:56.403671 28171 provisioner.cpp:324] Ignoring destroy request for unknown container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a.8aefad1c-5c65-42c8-bdb2-245f81ba76eb I1213 00:04:56.403920 28176 containerizer.cpp:2379] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_aXEmzW/containers/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a/containers/8aefad1c-5c65-42c8-bdb2-245f81ba76eb/termination' I1213 00:04:56.404777 28153 sched.cpp:2008] Asked to stop the driver I1213 00:04:56.404983 28176 sched.cpp:1193] Stopping framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.405261 28178 master.cpp:7287] Processing TEARDOWN call for framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 I1213 00:04:56.405300 28178 master.cpp:7299] Removing framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (default) at [email protected]:35837 I1213 00:04:56.405522 28183 hierarchical.cpp:391] Deactivated framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.405534 28178 master.cpp:7867] Updating the state of task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I1213 00:04:56.405577 28186 slave.cpp:2579] Asked to shut down framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 by [email protected]:35837 I1213 00:04:56.405612 28186 slave.cpp:2604] Shutting down framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.405670 28186 slave.cpp:4994] Shutting down executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 at executor(1)@172.17.0.3:54056 I1213 00:04:56.406314 28178 master.cpp:7963] Removing task a726ec84-49d4-4497-ae61-a774ce6f3070 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:56.406496 28171 containerizer.cpp:2100] Destroying container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a in RUNNING state I1213 00:04:56.406589 28174 hierarchical.cpp:1023] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 from framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.406855 28171 launcher.cpp:149] Asked to destroy container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a I1213 00:04:56.407169 28185 hierarchical.cpp:342] Removed framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.418638 28183 slave.cpp:4313] Got exited event for executor(1)@172.17.0.3:54056 I1213 00:04:56.503118 28174 containerizer.cpp:2463] Container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a has exited I1213 00:04:56.505563 28182 provisioner.cpp:324] Ignoring destroy request for unknown container 4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a I1213 00:04:56.506820 28184 slave.cpp:4676] Executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 terminated with signal Killed I1213 00:04:56.506901 28184 slave.cpp:4780] Cleaning up executor 'a726ec84-49d4-4497-ae61-a774ce6f3070' of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 at executor(1)@172.17.0.3:54056 I1213 00:04:56.507207 28183 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070/runs/4edc5111-f0e6-4e3a-9d4a-eb64b2e39e8a' for gc 6.99999413081185days in the future I1213 00:04:56.507383 28184 slave.cpp:4868] Cleaning up framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.507390 28183 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000/executors/a726ec84-49d4-4497-ae61-a774ce6f3070' for gc 6.9999941282637days in the future I1213 00:04:56.507608 28183 status_update_manager.cpp:285] Closing status update streams for framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.507666 28183 status_update_manager.cpp:531] Cleaning up status update stream for task a726ec84-49d4-4497-ae61-a774ce6f3070 of framework 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000 I1213 00:04:56.507755 28182 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_dxwaDI/slaves/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0/frameworks/2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-0000' for gc 6.99999412446518days in the future I1213 00:04:56.508394 28182 slave.cpp:791] Agent terminating I1213 00:04:56.508668 28174 master.cpp:1258] Agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) disconnected I1213 00:04:56.508711 28174 master.cpp:2977] Disconnecting agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:56.508805 28174 master.cpp:2996] Deactivating agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 at slave(579)@172.17.0.3:35837 (be9fb70992f0) I1213 00:04:56.508949 28176 hierarchical.cpp:589] Agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 deactivated I1213 00:04:56.514641 28172 master.cpp:1097] Master terminating I1213 00:04:56.515429 28171 hierarchical.cpp:522] Removed agent 2fbeebd3-3f2b-4840-aec0-1fffd2da7e36-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 (598 ms) [ RUN ] ContentType/AgentAPITest.AttachContainerOutputFailure/0 I1213 00:04:56.528928 28185 slave.cpp:209] Mesos agent started on (580)@172.17.0.3:35837 I1213 00:04:56.528995 28185 slave.cpp:210] Flags at startup: --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_mcvizj" I1213 00:04:56.529871 28185 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y/credential' I1213 00:04:56.530104 28185 slave.cpp:347] Agent using credential for: test-principal I1213 00:04:56.530133 28185 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_BRdy2y/http_credentials' I1213 00:04:56.530418 28185 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1213 00:04:56.530621 28185 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1213 00:04:56.531749 28185 slave.cpp:534] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1213 00:04:56.531819 28185 slave.cpp:542] Agent attributes: [ ] I1213 00:04:56.531831 28185 slave.cpp:547] Agent hostname: be9fb70992f0 I1213 00:04:56.533812 28176 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_mcvizj/meta' I1213 00:04:56.534131 28174 status_update_manager.cpp:203] Recovering status update manager I1213 00:04:56.534488 28183 slave.cpp:5415] Finished recovery I1213 00:04:56.534909 28183 slave.cpp:5589] Querying resource estimator for oversubscribable resources I1213 00:04:56.535220 28183 slave.cpp:5603] Received oversubscribable resources {} from the resource estimator Build timed out (after 300 minutes). Marking the build as failed. Build was aborted ++ docker rmi mesos-1481585008-362 Error response from daemon: conflict: unable to remove repository reference "mesos-1481585008-362" (must force) - container be9fb70992f0 is using its referenced image 4fec6951f648
