You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/02/04 00:57:22 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3217
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3217/changes>
Changes:
[mpark] Added `--3way` option to `support/apply-reviews.py`.
------------------------------------------
[...truncated 158837 lines...]
I0203 20:55:46.433248 30187 master.cpp:3117] Disconnecting agent bc34a22c-ae11-4df4-a38d-9217cb673fdb-S0 at slave(597)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.433344 30187 master.cpp:3136] Deactivating agent bc34a22c-ae11-4df4-a38d-9217cb673fdb-S0 at slave(597)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.433993 30194 hierarchical.cpp:590] Agent bc34a22c-ae11-4df4-a38d-9217cb673fdb-S0 deactivated
I0203 20:55:46.437567 30195 master.cpp:1102] Master terminating
I0203 20:55:46.438271 30198 hierarchical.cpp:521] Removed agent bc34a22c-ae11-4df4-a38d-9217cb673fdb-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1 (601 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0
I0203 20:55:46.444191 30164 cluster.cpp:160] Creating default 'local' authorizer
I0203 20:55:46.446254 30189 master.cpp:383] Master 71c060d4-9da3-4a3b-9f60-972ab9aa3b86 (30ef7a500df1) started on 172.17.0.4:39151
I0203 20:55:46.446398 30189 master.cpp:385] 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/OMKEvX/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" --max_unreachable_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/OMKEvX/master" --zk_session_timeout="10secs"
I0203 20:55:46.446687 30189 master.cpp:435] Master only allowing authenticated frameworks to register
I0203 20:55:46.446702 30189 master.cpp:449] Master only allowing authenticated agents to register
I0203 20:55:46.446714 30189 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0203 20:55:46.446727 30189 credentials.hpp:37] Loading credentials for authentication from '/tmp/OMKEvX/credentials'
I0203 20:55:46.446976 30189 master.cpp:507] Using default 'crammd5' authenticator
I0203 20:55:46.447103 30189 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0203 20:55:46.447237 30189 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0203 20:55:46.447350 30189 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0203 20:55:46.447450 30189 master.cpp:587] Authorization enabled
I0203 20:55:46.447594 30193 hierarchical.cpp:159] Initialized hierarchical allocator process
I0203 20:55:46.447594 30195 whitelist_watcher.cpp:77] No whitelist given
I0203 20:55:46.449779 30187 master.cpp:2123] Elected as the leading master!
I0203 20:55:46.449805 30187 master.cpp:1645] Recovering from registrar
I0203 20:55:46.449936 30188 registrar.cpp:329] Recovering registrar
I0203 20:55:46.450441 30192 registrar.cpp:362] Successfully fetched the registry (0B) in 459776ns
I0203 20:55:46.450526 30192 registrar.cpp:461] Applied 1 operations in 13771ns; attempting to update the registry
I0203 20:55:46.451355 30192 registrar.cpp:506] Successfully updated the registry in 777984ns
I0203 20:55:46.451467 30192 registrar.cpp:392] Successfully recovered registrar
I0203 20:55:46.451846 30187 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0203 20:55:46.451869 30194 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0203 20:55:46.453948 30164 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0203 20:55:46.454390 30164 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0203 20:55:46.454488 30164 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0203 20:55:46.454525 30164 provisioner.cpp:249] Using default backend 'copy'
I0203 20:55:46.457432 30164 cluster.cpp:446] Creating default 'local' authorizer
I0203 20:55:46.458747 30185 slave.cpp:209] Mesos agent started on (598)@172.17.0.4:39151
I0203 20:55:46.458765 30185 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_0_yrLWF0/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_0_yrLWF0/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_0_yrLWF0/http_credentials" --http_heartbeat_interval="30secs" --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_0_yrLWF0" --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_0_BClN5t"
I0203 20:55:46.459183 30185 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_yrLWF0/credential'
I0203 20:55:46.459337 30185 slave.cpp:352] Agent using credential for: test-principal
I0203 20:55:46.459358 30185 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_yrLWF0/http_credentials'
I0203 20:55:46.459609 30185 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0203 20:55:46.459723 30185 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0203 20:55:46.459903 30164 sched.cpp:232] Version: 1.2.0
I0203 20:55:46.460443 30198 sched.cpp:336] New master detected at master@172.17.0.4:39151
I0203 20:55:46.460530 30198 sched.cpp:407] Authenticating with master master@172.17.0.4:39151
I0203 20:55:46.460577 30198 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0203 20:55:46.460865 30187 authenticatee.cpp:121] Creating new client SASL connection
I0203 20:55:46.460932 30185 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0203 20:55:46.461045 30185 slave.cpp:547] Agent attributes: [ ]
I0203 20:55:46.461063 30185 slave.cpp:552] Agent hostname: 30ef7a500df1
I0203 20:55:46.461179 30193 status_update_manager.cpp:177] Pausing sending status updates
I0203 20:55:46.461257 30183 master.cpp:7018] Authenticating scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.461398 30195 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1166)@172.17.0.4:39151
I0203 20:55:46.461668 30183 authenticator.cpp:98] Creating new server SASL connection
I0203 20:55:46.461861 30198 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0203 20:55:46.461892 30198 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0203 20:55:46.461983 30198 authenticator.cpp:204] Received SASL authentication start
I0203 20:55:46.462072 30198 authenticator.cpp:326] Authentication requires more steps
I0203 20:55:46.462296 30189 authenticatee.cpp:259] Received SASL authentication step
I0203 20:55:46.462472 30196 authenticator.cpp:232] Received SASL authentication step
I0203 20:55:46.462658 30196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '30ef7a500df1' server FQDN: '30ef7a500df1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0203 20:55:46.462584 30190 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/meta'
I0203 20:55:46.462679 30196 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0203 20:55:46.462718 30196 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0203 20:55:46.462781 30196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '30ef7a500df1' server FQDN: '30ef7a500df1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0203 20:55:46.462957 30188 status_update_manager.cpp:203] Recovering status update manager
I0203 20:55:46.462977 30196 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0203 20:55:46.463001 30196 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0203 20:55:46.463027 30196 authenticator.cpp:318] Authentication success
I0203 20:55:46.463135 30190 containerizer.cpp:599] Recovering containerizer
I0203 20:55:46.463305 30183 authenticatee.cpp:299] Authentication success
I0203 20:55:46.463369 30189 master.cpp:7048] Successfully authenticated principal 'test-principal' at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.463522 30193 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1166)@172.17.0.4:39151
I0203 20:55:46.463680 30195 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:39151
I0203 20:55:46.463798 30195 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:39151
I0203 20:55:46.463960 30195 sched.cpp:869] Will retry registration in 694.311305ms if necessary
I0203 20:55:46.464174 30184 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.464238 30184 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0203 20:55:46.464699 30184 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0203 20:55:46.465122 30193 provisioner.cpp:410] Provisioner recovery complete
I0203 20:55:46.465476 30184 hierarchical.cpp:277] Added framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.465626 30192 slave.cpp:5422] Finished recovery
I0203 20:55:46.465643 30184 hierarchical.cpp:1739] No allocations performed
I0203 20:55:46.465644 30183 sched.cpp:759] Framework registered with 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.465698 30184 hierarchical.cpp:1829] No inverse offers to send out!
I0203 20:55:46.465716 30183 sched.cpp:773] Scheduler::registered took 21893ns
I0203 20:55:46.465777 30184 hierarchical.cpp:1345] Performed allocation for 0 agents in 168864ns
I0203 20:55:46.466066 30192 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0203 20:55:46.466276 30185 status_update_manager.cpp:177] Pausing sending status updates
I0203 20:55:46.466289 30190 slave.cpp:929] New master detected at master@172.17.0.4:39151
I0203 20:55:46.466425 30190 slave.cpp:964] Detecting new master
I0203 20:55:46.466563 30190 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0203 20:55:46.475672 30193 slave.cpp:991] Authenticating with master master@172.17.0.4:39151
I0203 20:55:46.475751 30193 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0203 20:55:46.475924 30186 authenticatee.cpp:121] Creating new client SASL connection
I0203 20:55:46.476135 30186 master.cpp:7018] Authenticating slave(598)@172.17.0.4:39151
I0203 20:55:46.476229 30191 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1167)@172.17.0.4:39151
I0203 20:55:46.476399 30195 authenticator.cpp:98] Creating new server SASL connection
I0203 20:55:46.476577 30184 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0203 20:55:46.476603 30184 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0203 20:55:46.476691 30197 authenticator.cpp:204] Received SASL authentication start
I0203 20:55:46.476732 30197 authenticator.cpp:326] Authentication requires more steps
I0203 20:55:46.476830 30184 authenticatee.cpp:259] Received SASL authentication step
I0203 20:55:46.477016 30195 authenticator.cpp:232] Received SASL authentication step
I0203 20:55:46.477044 30195 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '30ef7a500df1' server FQDN: '30ef7a500df1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0203 20:55:46.477064 30195 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0203 20:55:46.477100 30195 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0203 20:55:46.477134 30195 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '30ef7a500df1' server FQDN: '30ef7a500df1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0203 20:55:46.477155 30195 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0203 20:55:46.477193 30195 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0203 20:55:46.477221 30195 authenticator.cpp:318] Authentication success
I0203 20:55:46.477313 30192 authenticatee.cpp:299] Authentication success
I0203 20:55:46.477367 30195 master.cpp:7048] Successfully authenticated principal 'test-principal' at slave(598)@172.17.0.4:39151
I0203 20:55:46.477402 30192 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1167)@172.17.0.4:39151
I0203 20:55:46.477685 30195 slave.cpp:1086] Successfully authenticated with master master@172.17.0.4:39151
I0203 20:55:46.477828 30195 slave.cpp:1508] Will retry registration in 5.856655ms if necessary
I0203 20:55:46.477979 30192 master.cpp:5303] Registering agent at slave(598)@172.17.0.4:39151 (30ef7a500df1) with id 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0
I0203 20:55:46.478400 30183 registrar.cpp:461] Applied 1 operations in 44578ns; attempting to update the registry
I0203 20:55:46.479598 30195 registrar.cpp:506] Successfully updated the registry in 1.140992ms
I0203 20:55:46.480248 30195 slave.cpp:4286] Received ping from slave-observer(550)@172.17.0.4:39151
I0203 20:55:46.480464 30189 slave.cpp:1132] Registered with master master@172.17.0.4:39151; given agent ID 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0
I0203 20:55:46.480489 30189 fetcher.cpp:90] Clearing fetcher cache
I0203 20:55:46.480413 30192 master.cpp:5377] Registered agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0203 20:55:46.480710 30183 status_update_manager.cpp:184] Resuming sending status updates
I0203 20:55:46.480859 30193 hierarchical.cpp:488] Added agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 (30ef7a500df1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0203 20:55:46.480926 30189 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/meta/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/slave.info'
I0203 20:55:46.481302 30189 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0203 20:55:46.481458 30189 master.cpp:5876] Received update of agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1) with total oversubscribed resources {}
I0203 20:55:46.481760 30193 hierarchical.cpp:1829] No inverse offers to send out!
I0203 20:55:46.481853 30193 hierarchical.cpp:1345] Performed allocation for 1 agents in 871406ns
I0203 20:55:46.481974 30193 hierarchical.cpp:561] Agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 (30ef7a500df1) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0203 20:55:46.482132 30193 hierarchical.cpp:1739] No allocations performed
I0203 20:55:46.482163 30184 master.cpp:6847] Sending 1 offers to framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.482173 30193 hierarchical.cpp:1829] No inverse offers to send out!
I0203 20:55:46.482225 30193 hierarchical.cpp:1345] Performed allocation for 1 agents in 152917ns
I0203 20:55:46.482569 30195 sched.cpp:933] Scheduler::resourceOffers took 84566ns
I0203 20:55:46.484074 30189 master.cpp:3730] Processing ACCEPT call for offers: [ 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-O0 ] on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1) for framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.484195 30189 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4
I0203 20:55:46.485985 30185 master.cpp:8902] Adding task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.486289 30185 master.cpp:4380] Launching task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.486906 30190 slave.cpp:1576] Got assigned task 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' for framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.487515 30190 slave.cpp:1736] Launching task 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' for framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.488061 30190 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750' to user 'mesos'
I0203 20:55:46.488292 30190 slave.cpp:6331] Launching executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750'
I0203 20:55:46.488793 30193 containerizer.cpp:992] Starting container e631b892-e078-4eaf-ba69-4e007a933750 for executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.488912 30190 slave.cpp:2058] Queued task 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' for executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.489009 30190 slave.cpp:882] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750'
I0203 20:55:46.493223 30192 containerizer.cpp:1541] 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.4:39151"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t\/slaves\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0\/frameworks\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000\/executors\/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4\/runs\/e631b892-e078-4eaf-ba69-4e007a933750"},{"name":"MESOS_EXECUTOR_ID","value":"ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(598)@172.17.0.4:39151"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t\/slaves\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0\/frameworks\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000\/executors\/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4\/runs\/e631b892-e078-4eaf-ba69-4e007a933750"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t\/slaves\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0\/frameworks\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000\/executors\/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4\/runs\/e631b892-e078-4eaf-ba69-4e007a933750\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t\/slaves\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0\/frameworks\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000\/executors\/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4\/runs\/e631b892-e078-4eaf-ba69-4e007a933750\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t\/slaves\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0\/frameworks\/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000\/executors\/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4\/runs\/e631b892-e078-4eaf-ba69-4e007a933750"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_yrLWF0/containers/e631b892-e078-4eaf-ba69-4e007a933750" --unshare_namespace_mnt="false"'
I0203 20:55:46.496214 30192 launcher.cpp:135] Forked child with pid '10482' for container 'e631b892-e078-4eaf-ba69-4e007a933750'
I0203 20:55:46.499105 30183 fetcher.cpp:349] Starting to fetch URIs for container: e631b892-e078-4eaf-ba69-4e007a933750, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750
I0203 20:55:46.702476 30190 slave.cpp:3325] Got registration for executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 from executor(1)@172.17.0.4:53549
I0203 20:55:46.704731 30194 slave.cpp:2271] Sending queued task 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' to executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 at executor(1)@172.17.0.4:53549
I0203 20:55:46.722978 30183 slave.cpp:3756] Handling status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 from executor(1)@172.17.0.4:53549
I0203 20:55:46.724491 30190 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.724535 30190 status_update_manager.cpp:500] Creating StatusUpdate stream for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.725051 30190 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 to the agent
I0203 20:55:46.725371 30189 slave.cpp:4196] Forwarding the update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 to master@172.17.0.4:39151
I0203 20:55:46.725653 30189 slave.cpp:4090] Status update manager successfully handled status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.725705 30189 slave.cpp:4106] Sending acknowledgement for status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 to executor(1)@172.17.0.4:53549
I0203 20:55:46.725792 30191 master.cpp:6021] Status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 from agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.725855 30191 master.cpp:6089] Forwarding status update TASK_RUNNING (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.726078 30191 master.cpp:8157] Updating the state of task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0203 20:55:46.726464 30194 sched.cpp:1041] Scheduler::statusUpdate took 154515ns
I0203 20:55:46.729292 30194 master.cpp:5017] Processing ACKNOWLEDGE call ff81a403-c12f-4c7b-9b38-ca0691986a3a for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151 on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0
I0203 20:55:46.729697 30186 status_update_manager.cpp:395] Received status update acknowledgement (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.730268 30192 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: ff81a403-c12f-4c7b-9b38-ca0691986a3a) for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.730921 30191 process.cpp:3697] Handling HTTP event for process 'slave(598)' with path: '/slave(598)/api/v1'
I0203 20:55:46.732148 30184 http.cpp:303] HTTP POST for /slave(598)/api/v1 from 172.17.0.4:38955
I0203 20:55:46.732640 30184 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0203 20:55:46.733274 30194 containerizer.cpp:1808] Starting nested container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.733553 30194 containerizer.cpp:1832] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750/containers/3a04b4bb-d193-4014-9eb7-ecc86d031ddc' to user 'mesos'
I0203 20:55:46.735779 30189 switchboard.cpp:571] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-6a69911f-4391-4eaf-b9ca-61314914df2d" --stderr_from_fd="62" --stderr_to_fd="2" --stdin_to_fd="29" --stdout_from_fd="41" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.738647 30189 switchboard.cpp:601] Created I/O switchboard server (pid: 10541) listening on socket file '/tmp/mesos-io-switchboard-6a69911f-4391-4eaf-b9ca-61314914df2d' for container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.740207 30191 containerizer.cpp:1541] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{},"err":{"fd":63,"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_0_yrLWF0/containers/e631b892-e078-4eaf-ba69-4e007a933750/containers/3a04b4bb-d193-4014-9eb7-ecc86d031ddc" --unshare_namespace_mnt="false"'
I0203 20:55:46.742534 30191 launcher.cpp:135] Forked child with pid '10542' for container 'e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc'
I0203 20:55:46.745380 30192 fetcher.cpp:349] Starting to fetch URIs for container: e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750/containers/3a04b4bb-d193-4014-9eb7-ecc86d031ddc
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(621)@172.17.0.4:39151 that it is currently executing.
W0203 20:55:46.870740 30183 http.cpp:2714] Launch nested container session connection for container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc closed
I0203 20:55:46.870860 30183 containerizer.cpp:2119] Destroying container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc in RUNNING state
I0203 20:55:46.871186 30183 launcher.cpp:151] Asked to destroy container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.888432 30185 http.cpp:2699] Received EOF attach response for e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.931856 30185 switchboard.cpp:873] I/O switchboard server process for container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc has terminated (status=0)
I0203 20:55:46.932509 30194 containerizer.cpp:2482] Container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc has exited
I0203 20:55:46.934139 30188 provisioner.cpp:484] Ignoring destroy request for unknown container e631b892-e078-4eaf-ba69-4e007a933750.3a04b4bb-d193-4014-9eb7-ecc86d031ddc
I0203 20:55:46.934459 30196 containerizer.cpp:2398] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_yrLWF0/containers/e631b892-e078-4eaf-ba69-4e007a933750/containers/3a04b4bb-d193-4014-9eb7-ecc86d031ddc/termination'
I0203 20:55:46.935194 30164 sched.cpp:2021] Asked to stop the driver
I0203 20:55:46.935305 30198 sched.cpp:1203] Stopping framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.935657 30190 master.cpp:7560] Processing TEARDOWN call for framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.935715 30190 master.cpp:7572] Removing framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.935752 30190 master.cpp:3080] Deactivating framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (default) at scheduler-675e1ddf-99c9-4a6c-89f4-20dc097c4e1d@172.17.0.4:39151
I0203 20:55:46.936094 30188 hierarchical.cpp:393] Deactivated framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.936192 30190 master.cpp:8157] Updating the state of task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0203 20:55:46.936178 30188 slave.cpp:2598] Asked to shut down framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 by master@172.17.0.4:39151
I0203 20:55:46.936373 30188 slave.cpp:2623] Shutting down framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.936511 30188 slave.cpp:5005] Shutting down executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 at executor(1)@172.17.0.4:53549
I0203 20:55:46.936976 30190 master.cpp:8251] Removing task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:46.937669 30195 containerizer.cpp:2119] Destroying container e631b892-e078-4eaf-ba69-4e007a933750 in RUNNING state
I0203 20:55:46.937968 30195 launcher.cpp:151] Asked to destroy container e631b892-e078-4eaf-ba69-4e007a933750
I0203 20:55:46.938256 30191 hierarchical.cpp:1033] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 from framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.938602 30191 hierarchical.cpp:343] Removed framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:46.952392 30194 slave.cpp:4328] Got exited event for executor(1)@172.17.0.4:53549
I0203 20:55:47.033854 30188 containerizer.cpp:2482] Container e631b892-e078-4eaf-ba69-4e007a933750 has exited
I0203 20:55:47.036918 30188 provisioner.cpp:484] Ignoring destroy request for unknown container e631b892-e078-4eaf-ba69-4e007a933750
I0203 20:55:47.037864 30188 slave.cpp:4691] Executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 terminated with signal Killed
I0203 20:55:47.037925 30188 slave.cpp:4791] Cleaning up executor 'ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000 at executor(1)@172.17.0.4:53549
I0203 20:55:47.038360 30185 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4/runs/e631b892-e078-4eaf-ba69-4e007a933750' for gc 6.9999995573837days in the future
I0203 20:55:47.038478 30188 slave.cpp:4879] Cleaning up framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:47.038588 30185 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000/executors/ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4' for gc 6.99999955540444days in the future
I0203 20:55:47.038735 30185 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_BClN5t/slaves/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0/frameworks/71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000' for gc 6.99999955278815days in the future
I0203 20:55:47.038612 30198 status_update_manager.cpp:285] Closing status update streams for framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:47.038983 30198 status_update_manager.cpp:531] Cleaning up status update stream for task ed23b1a1-af3f-405a-9c29-ffcf1e3e12b4 of framework 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-0000
I0203 20:55:47.041291 30194 slave.cpp:801] Agent terminating
I0203 20:55:47.041576 30189 master.cpp:1263] Agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1) disconnected
I0203 20:55:47.041606 30189 master.cpp:3117] Disconnecting agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:47.041857 30189 master.cpp:3136] Deactivating agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 at slave(598)@172.17.0.4:39151 (30ef7a500df1)
I0203 20:55:47.042058 30194 hierarchical.cpp:590] Agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0 deactivated
I0203 20:55:47.046427 30198 master.cpp:1102] Master terminating
I0203 20:55:47.047765 30187 hierarchical.cpp:521] Removed agent 71c060d4-9da3-4a3b-9f60-972ab9aa3b86-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0 (607 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1
I0203 20:55:47.053449 30164 cluster.cpp:160] Creating default 'local' authorizer
I0203 20:55:47.055675 30196 master.cpp:383] Master 0fbe9149-2990-421a-b9be-8fa8ddad1708 (30ef7a500df1) started on 172.17.0.4:39151
I0203 20:55:47.055927 30196 master.cpp:385] 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/1ZfoXP/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" --max_unreachable_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/1ZfoXP/master" --zk_session_timeout="10secs"
I0203 20:55:47.056702 30196 master.cpp:435] Master only allowing authenticated frameworks to register
I0203 20:55:47.056726 30196 master.cpp:449] Master only allowing authenticated agents to register
I0203 20:55:47.056761 30196 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0203 20:55:47.056794 30196 credentials.hpp:37] Loading credentials for authentication from '/tmp/1ZfoXP/credentials'
I0203 20:55:47.057178 30196 master.cpp:507] Using default 'crammd5' authenticator
I0203 20:55:47.057332 30196 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0203 20:55:47.057499 30196 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0203 20:55:47.057641 30196 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0203 20:55:47.057778 30196 master.cpp:587] Authorization enabled
I0203 20:55:47.058046 30190 hierarchical.cpp:159] Initialized hierarchical allocator process
I0203 20:55:47.058085 30195 whitelist_watcher.cpp:77] No whitelist given
I0203 20:55:47.060459 30198 master.cpp:2123] Elected as the leading master!
I0203 20:55:47.060492 30198 master.cpp:1645] Recovering from registrar
I0203 20:55:47.060649 30192 registrar.cpp:329] Recovering registrar
I0203 20:55:47.061645 30192 registrar.cpp:362] Successfully fetched the registry (0B) in 843008ns
I0203 20:55:47.061750 30192 registrar.cpp:461] Applied 1 operations in 23504ns; attempting to update the registry
I0203 20:55:47.062547 30196 registrar.cpp:506] Successfully updated the registry in 732928ns
I0203 20:55:47.062732 30196 registrar.cpp:392] Successfully recovered registrar
I0203 20:55:47.063197 30186 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0203 20:55:47.063261 30193 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1486151951-19197
Error response from daemon: conflict: unable to remove repository reference "mesos-1486151951-19197" (must force) - container 30ef7a500df1 is using its referenced image d9237d17936f
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3218
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3218/changes>