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/02 10:28:57 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3212
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3212/>
------------------------------------------
[...truncated 157356 lines...]
I0202 06:22:00.278003 27122 hierarchical.cpp:512] Removed agent c4c3903e-c87a-4175-b024-927a4940766d-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0 (418 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1
I0202 06:22:00.284019 27111 cluster.cpp:160] Creating default 'local' authorizer
I0202 06:22:00.285456 27117 master.cpp:383] Master 69ede5f4-257e-4afc-a5bc-a1669347de9a (7f936c7a25f4) started on 172.17.0.4:58247
I0202 06:22:00.285481 27117 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/RX9trR/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="/usr/local/share/mesos/webui" --work_dir="/tmp/RX9trR/master" --zk_session_timeout="10secs"
I0202 06:22:00.285825 27117 master.cpp:435] Master only allowing authenticated frameworks to register
I0202 06:22:00.285845 27117 master.cpp:449] Master only allowing authenticated agents to register
I0202 06:22:00.285857 27117 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0202 06:22:00.285864 27117 credentials.hpp:37] Loading credentials for authentication from '/tmp/RX9trR/credentials'
I0202 06:22:00.286058 27117 master.cpp:507] Using default 'crammd5' authenticator
I0202 06:22:00.286133 27117 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0202 06:22:00.286227 27117 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0202 06:22:00.286288 27117 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0202 06:22:00.286368 27117 master.cpp:587] Authorization enabled
I0202 06:22:00.286516 27127 whitelist_watcher.cpp:77] No whitelist given
I0202 06:22:00.286561 27120 hierarchical.cpp:152] Initialized hierarchical allocator process
I0202 06:22:00.287834 27115 master.cpp:2123] Elected as the leading master!
I0202 06:22:00.287853 27115 master.cpp:1645] Recovering from registrar
I0202 06:22:00.287955 27118 registrar.cpp:329] Recovering registrar
I0202 06:22:00.288317 27122 registrar.cpp:362] Successfully fetched the registry (0B) in 313856ns
I0202 06:22:00.288367 27122 registrar.cpp:461] Applied 1 operations in 9048ns; attempting to update the registry
I0202 06:22:00.288801 27115 registrar.cpp:506] Successfully updated the registry in 411136ns
I0202 06:22:00.288851 27115 registrar.cpp:392] Successfully recovered registrar
I0202 06:22:00.289193 27122 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0202 06:22:00.289230 27118 hierarchical.cpp:179] Skipping recovery of hierarchical allocator: nothing to recover
I0202 06:22:00.290779 27111 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0202 06:22:00.291169 27111 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 06:22:00.291234 27111 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 06:22:00.291306 27111 provisioner.cpp:249] Using default backend 'copy'
I0202 06:22:00.293277 27111 cluster.cpp:446] Creating default 'local' authorizer
I0202 06:22:00.294090 27126 slave.cpp:209] Mesos agent started on (594)@172.17.0.4:58247
I0202 06:22:00.294137 27126 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_LaunchNestedContainerSessionWithTTY_1_VVcnl8/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_LaunchNestedContainerSessionWithTTY_1_VVcnl8/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_LaunchNestedContainerSessionWithTTY_1_VVcnl8/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_LaunchNestedContainerSessionWithTTY_1_VVcnl8" --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_LaunchNestedContainerSessionWithTTY_1_iwbqTu"
I0202 06:22:00.294615 27126 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_VVcnl8/credential'
I0202 06:22:00.294795 27126 slave.cpp:352] Agent using credential for: test-principal
I0202 06:22:00.294814 27126 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_VVcnl8/http_credentials'
I0202 06:22:00.294935 27111 sched.cpp:232] Version: 1.2.0
I0202 06:22:00.294939 27126 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0202 06:22:00.295006 27126 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0202 06:22:00.295346 27116 sched.cpp:336] New master detected at master@172.17.0.4:58247
I0202 06:22:00.295404 27116 sched.cpp:407] Authenticating with master master@172.17.0.4:58247
I0202 06:22:00.295522 27116 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0202 06:22:00.295541 27126 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 06:22:00.295629 27126 slave.cpp:547] Agent attributes: [ ]
I0202 06:22:00.295660 27126 slave.cpp:552] Agent hostname: 7f936c7a25f4
I0202 06:22:00.295781 27116 authenticatee.cpp:121] Creating new client SASL connection
I0202 06:22:00.295910 27113 status_update_manager.cpp:177] Pausing sending status updates
I0202 06:22:00.296013 27116 master.cpp:7018] Authenticating scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.296157 27124 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1154)@172.17.0.4:58247
I0202 06:22:00.296254 27127 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/meta'
I0202 06:22:00.296402 27114 authenticator.cpp:98] Creating new server SASL connection
I0202 06:22:00.296556 27122 status_update_manager.cpp:203] Recovering status update manager
I0202 06:22:00.296592 27115 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 06:22:00.296783 27122 containerizer.cpp:599] Recovering containerizer
I0202 06:22:00.296797 27115 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 06:22:00.297106 27124 authenticator.cpp:204] Received SASL authentication start
I0202 06:22:00.297286 27124 authenticator.cpp:326] Authentication requires more steps
I0202 06:22:00.297358 27124 authenticatee.cpp:259] Received SASL authentication step
I0202 06:22:00.297420 27124 authenticator.cpp:232] Received SASL authentication step
I0202 06:22:00.297442 27124 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7f936c7a25f4' server FQDN: '7f936c7a25f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0202 06:22:00.297454 27124 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 06:22:00.297471 27124 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 06:22:00.297493 27124 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7f936c7a25f4' server FQDN: '7f936c7a25f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0202 06:22:00.297503 27124 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 06:22:00.297510 27124 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 06:22:00.297526 27124 authenticator.cpp:318] Authentication success
I0202 06:22:00.297644 27113 master.cpp:7048] Successfully authenticated principal 'test-principal' at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.297705 27126 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1154)@172.17.0.4:58247
I0202 06:22:00.297854 27112 authenticatee.cpp:299] Authentication success
I0202 06:22:00.297974 27112 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:58247
I0202 06:22:00.297991 27112 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:58247
I0202 06:22:00.298038 27112 sched.cpp:869] Will retry registration in 352.756543ms if necessary
I0202 06:22:00.298089 27118 provisioner.cpp:410] Provisioner recovery complete
I0202 06:22:00.298136 27114 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.298244 27114 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0202 06:22:00.298414 27123 slave.cpp:5422] Finished recovery
I0202 06:22:00.298501 27114 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0202 06:22:00.298682 27114 sched.cpp:759] Framework registered with 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.298717 27113 hierarchical.cpp:272] Added framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.298863 27113 hierarchical.cpp:1697] No allocations performed
I0202 06:22:00.298882 27113 hierarchical.cpp:1786] No inverse offers to send out!
I0202 06:22:00.298899 27123 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0202 06:22:00.298935 27113 hierarchical.cpp:1322] Performed allocation for 0 agents in 77384ns
I0202 06:22:00.299090 27113 slave.cpp:929] New master detected at master@172.17.0.4:58247
I0202 06:22:00.299094 27118 status_update_manager.cpp:177] Pausing sending status updates
I0202 06:22:00.299116 27113 slave.cpp:964] Detecting new master
I0202 06:22:00.299206 27113 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0202 06:22:00.299012 27114 sched.cpp:773] Scheduler::registered took 18484ns
I0202 06:22:00.311691 27124 slave.cpp:991] Authenticating with master master@172.17.0.4:58247
I0202 06:22:00.311722 27124 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0202 06:22:00.311825 27115 authenticatee.cpp:121] Creating new client SASL connection
I0202 06:22:00.312135 27117 master.cpp:7018] Authenticating slave(594)@172.17.0.4:58247
I0202 06:22:00.312197 27117 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1155)@172.17.0.4:58247
I0202 06:22:00.312264 27115 authenticator.cpp:98] Creating new server SASL connection
I0202 06:22:00.312383 27117 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 06:22:00.312407 27117 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 06:22:00.312458 27117 authenticator.cpp:204] Received SASL authentication start
I0202 06:22:00.312511 27117 authenticator.cpp:326] Authentication requires more steps
I0202 06:22:00.312614 27117 authenticatee.cpp:259] Received SASL authentication step
I0202 06:22:00.312700 27117 authenticator.cpp:232] Received SASL authentication step
I0202 06:22:00.312726 27117 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7f936c7a25f4' server FQDN: '7f936c7a25f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0202 06:22:00.312741 27117 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 06:22:00.312755 27117 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 06:22:00.312784 27117 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7f936c7a25f4' server FQDN: '7f936c7a25f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0202 06:22:00.312795 27117 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 06:22:00.312805 27117 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 06:22:00.312824 27117 authenticator.cpp:318] Authentication success
I0202 06:22:00.312881 27123 authenticatee.cpp:299] Authentication success
I0202 06:22:00.312902 27124 master.cpp:7048] Successfully authenticated principal 'test-principal' at slave(594)@172.17.0.4:58247
I0202 06:22:00.312975 27123 slave.cpp:1086] Successfully authenticated with master master@172.17.0.4:58247
I0202 06:22:00.312989 27117 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1155)@172.17.0.4:58247
I0202 06:22:00.313047 27123 slave.cpp:1508] Will retry registration in 11.77402ms if necessary
I0202 06:22:00.313140 27124 master.cpp:5303] Registering agent at slave(594)@172.17.0.4:58247 (7f936c7a25f4) with id 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0
I0202 06:22:00.313292 27123 registrar.cpp:461] Applied 1 operations in 19086ns; attempting to update the registry
I0202 06:22:00.313628 27123 registrar.cpp:506] Successfully updated the registry in 307200ns
I0202 06:22:00.313869 27127 slave.cpp:4286] Received ping from slave-observer(542)@172.17.0.4:58247
I0202 06:22:00.313937 27127 slave.cpp:1132] Registered with master master@172.17.0.4:58247; given agent ID 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0
I0202 06:22:00.313979 27127 fetcher.cpp:90] Clearing fetcher cache
I0202 06:22:00.313954 27123 hierarchical.cpp:479] Added agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 (7f936c7a25f4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0202 06:22:00.313865 27124 master.cpp:5377] Registered agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 06:22:00.314230 27114 status_update_manager.cpp:184] Resuming sending status updates
I0202 06:22:00.314278 27123 hierarchical.cpp:1786] No inverse offers to send out!
I0202 06:22:00.314373 27123 hierarchical.cpp:1322] Performed allocation for 1 agents in 325123ns
I0202 06:22:00.314333 27127 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/meta/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/slave.info'
I0202 06:22:00.314501 27124 master.cpp:6847] Sending 1 offers to framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.314692 27124 sched.cpp:933] Scheduler::resourceOffers took 57606ns
I0202 06:22:00.314703 27127 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0202 06:22:00.315201 27114 master.cpp:5876] Received update of agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4) with total oversubscribed resources {}
I0202 06:22:00.315625 27114 master.cpp:3730] Processing ACCEPT call for offers: [ 69ede5f4-257e-4afc-a5bc-a1669347de9a-O0 ] on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4) for framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.315610 27124 hierarchical.cpp:550] Agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 (7f936c7a25f4) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0202 06:22:00.315677 27114 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 860d6762-e6bd-4be7-8fbd-b758aa025390
I0202 06:22:00.315734 27124 hierarchical.cpp:1697] No allocations performed
I0202 06:22:00.315755 27124 hierarchical.cpp:1786] No inverse offers to send out!
I0202 06:22:00.315778 27124 hierarchical.cpp:1322] Performed allocation for 1 agents in 64938ns
I0202 06:22:00.316684 27120 master.cpp:8902] Adding task 860d6762-e6bd-4be7-8fbd-b758aa025390 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.316849 27120 master.cpp:4380] Launching task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.317247 27117 slave.cpp:1576] Got assigned task '860d6762-e6bd-4be7-8fbd-b758aa025390' for framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.317608 27117 slave.cpp:1736] Launching task '860d6762-e6bd-4be7-8fbd-b758aa025390' for framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.318276 27117 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf' to user 'mesos'
I0202 06:22:00.318517 27117 slave.cpp:6331] Launching executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf'
I0202 06:22:00.318846 27120 containerizer.cpp:992] Starting container 6665cf7f-b577-42e3-aefc-bc0a43625cbf for executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.318886 27117 slave.cpp:2058] Queued task '860d6762-e6bd-4be7-8fbd-b758aa025390' for executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.318941 27117 slave.cpp:882] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf'
I0202 06:22:00.320900 27124 containerizer.cpp:1541] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.4:58247"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu\/slaves\/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0\/frameworks\/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000\/executors\/860d6762-e6bd-4be7-8fbd-b758aa025390\/runs\/6665cf7f-b577-42e3-aefc-bc0a43625cbf"},{"name":"MESOS_EXECUTOR_ID","value":"860d6762-e6bd-4be7-8fbd-b758aa025390"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"69ede5f4-257e-4afc-a5bc-a1669347de9a-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"69ede5f4-257e-4afc-a5bc-a1669347de9a-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(594)@172.17.0.4:58247"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu\/slaves\/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0\/frameworks\/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000\/executors\/860d6762-e6bd-4be7-8fbd-b758aa025390\/runs\/6665cf7f-b577-42e3-aefc-bc0a43625cbf"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu\/slaves\/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0\/frameworks\/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000\/executors\/860d6762-e6bd-4be7-8fbd-b758aa025390\/runs\/6665cf7f-b577-42e3-aefc-bc0a43625cbf\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu\/slaves\/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0\/frameworks\/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000\/executors\/860d6762-e6bd-4be7-8fbd-b758aa025390\/runs\/6665cf7f-b577-42e3-aefc-bc0a43625cbf\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu\/slaves\/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0\/frameworks\/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000\/executors\/860d6762-e6bd-4be7-8fbd-b758aa025390\/runs\/6665cf7f-b577-42e3-aefc-bc0a43625cbf"}" --pipe_read="9" --pipe_write="10" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_VVcnl8/containers/6665cf7f-b577-42e3-aefc-bc0a43625cbf" --unshare_namespace_mnt="false"'
I0202 06:22:00.323956 27124 launcher.cpp:135] Forked child with pid '31385' for container '6665cf7f-b577-42e3-aefc-bc0a43625cbf'
I0202 06:22:00.326356 27125 fetcher.cpp:349] Starting to fetch URIs for container: 6665cf7f-b577-42e3-aefc-bc0a43625cbf, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf
I0202 06:22:00.428326 27121 slave.cpp:3325] Got registration for executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 from executor(1)@172.17.0.4:42714
I0202 06:22:00.429158 27121 slave.cpp:2271] Sending queued task '860d6762-e6bd-4be7-8fbd-b758aa025390' to executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 at executor(1)@172.17.0.4:42714
I0202 06:22:00.435082 27121 slave.cpp:3756] Handling status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 from executor(1)@172.17.0.4:42714
I0202 06:22:00.435956 27122 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.435986 27122 status_update_manager.cpp:500] Creating StatusUpdate stream for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.436180 27122 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 to the agent
I0202 06:22:00.436341 27122 slave.cpp:4196] Forwarding the update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 to master@172.17.0.4:58247
I0202 06:22:00.436430 27122 slave.cpp:4090] Status update manager successfully handled status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.436467 27122 slave.cpp:4106] Sending acknowledgement for status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 to executor(1)@172.17.0.4:42714
I0202 06:22:00.436633 27122 master.cpp:6021] Status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 from agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.436668 27122 master.cpp:6089] Forwarding status update TASK_RUNNING (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.436754 27122 master.cpp:8157] Updating the state of task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0202 06:22:00.436910 27122 sched.cpp:1041] Scheduler::statusUpdate took 68917ns
I0202 06:22:00.437038 27122 master.cpp:5017] Processing ACKNOWLEDGE call 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247 on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0
I0202 06:22:00.437187 27122 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.437343 27122 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: 9b7d6d34-1540-4ca1-a56b-478ef51fd5ba) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.438222 27121 process.cpp:3697] Handling HTTP event for process 'slave(594)' with path: '/slave(594)/api/v1'
I0202 06:22:00.438751 27126 http.cpp:303] HTTP POST for /slave(594)/api/v1 from 172.17.0.4:52008
I0202 06:22:00.439026 27126 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0202 06:22:00.439280 27114 containerizer.cpp:1808] Starting nested container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.439541 27114 containerizer.cpp:1832] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf/containers/5222d2de-f785-4f54-98ff-a566639d41b8' to user 'mesos'
I0202 06:22:00.440732 27118 switchboard.cpp:434] Allocated pseudo terminal '/dev/pts/1' for container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.441007 27118 switchboard.cpp:571] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-0accb97e-617d-431d-b9e5-05cd7442bdf4" --stderr_from_fd="13" --stderr_to_fd="2" --stdin_to_fd="13" --stdout_from_fd="13" --stdout_to_fd="1" --tty="true" --wait_for_connection="true"' for container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.443558 27118 switchboard.cpp:601] Created I/O switchboard server (pid: 31405) listening on socket file '/tmp/mesos-io-switchboard-0accb97e-617d-431d-b9e5-05cd7442bdf4' for container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.444826 27119 containerizer.cpp:1541] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{"fd":14,"type":"FD"},"in":{"fd":14,"type":"FD"},"out":{"fd":14,"type":"FD"},"tty_slave_path":"\/dev\/pts\/1","user":"mesos"}" --pipe_read="13" --pipe_write="15" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_VVcnl8/containers/6665cf7f-b577-42e3-aefc-bc0a43625cbf/containers/5222d2de-f785-4f54-98ff-a566639d41b8" --unshare_namespace_mnt="false"'
I0202 06:22:00.447192 27119 launcher.cpp:135] Forked child with pid '31406' for container '6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8'
I0202 06:22:00.448778 27126 fetcher.cpp:349] Starting to fetch URIs for container: 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf/containers/5222d2de-f785-4f54-98ff-a566639d41b8
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(616)@172.17.0.4:58247 that it is currently executing.
I0202 06:22:00.516469 27116 http.cpp:2699] Received EOF attach response for 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.516711 27126 containerizer.cpp:2119] Destroying container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8 in RUNNING state
W0202 06:22:00.516748 27114 http.cpp:2714] Launch nested container session connection for container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8 closed
I0202 06:22:00.516813 27126 launcher.cpp:151] Asked to destroy container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.517175 27111 sched.cpp:2021] Asked to stop the driver
I0202 06:22:00.517276 27124 sched.cpp:1203] Stopping framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.517410 27116 master.cpp:7560] Processing TEARDOWN call for framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.517520 27116 master.cpp:7572] Removing framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.517541 27116 master.cpp:3080] Deactivating framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (default) at scheduler-a87c2b39-eb82-4671-94ac-a9c9c8f9822b@172.17.0.4:58247
I0202 06:22:00.517699 27116 master.cpp:8157] Updating the state of task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0202 06:22:00.517787 27118 slave.cpp:2598] Asked to shut down framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 by master@172.17.0.4:58247
I0202 06:22:00.518013 27118 slave.cpp:2623] Shutting down framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.518178 27116 master.cpp:8251] Removing task 860d6762-e6bd-4be7-8fbd-b758aa025390 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.518223 27118 slave.cpp:5005] Shutting down executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 at executor(1)@172.17.0.4:42714
I0202 06:22:00.517861 27113 hierarchical.cpp:387] Deactivated framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.518669 27113 hierarchical.cpp:1013] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 from framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.518779 27113 hierarchical.cpp:338] Removed framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.520439 27119 containerizer.cpp:2119] Destroying container 6665cf7f-b577-42e3-aefc-bc0a43625cbf in RUNNING state
I0202 06:22:00.528290 27119 slave.cpp:3756] Handling status update TASK_KILLED (UUID: e40d16a0-cb4d-4417-ba5a-95e498d42c04) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 from executor(1)@172.17.0.4:42714
W0202 06:22:00.528326 27119 slave.cpp:3825] Ignoring status update TASK_KILLED (UUID: e40d16a0-cb4d-4417-ba5a-95e498d42c04) for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 for terminating framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
E0202 06:22:00.573635 27119 switchboard.cpp:889] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status 1 for container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.573967 27120 containerizer.cpp:2482] Container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8 has exited
I0202 06:22:00.574930 27114 provisioner.cpp:484] Ignoring destroy request for unknown container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.575062 27119 containerizer.cpp:2398] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_VVcnl8/containers/6665cf7f-b577-42e3-aefc-bc0a43625cbf/containers/5222d2de-f785-4f54-98ff-a566639d41b8/termination'
I0202 06:22:00.575639 27119 launcher.cpp:151] Asked to destroy container 6665cf7f-b577-42e3-aefc-bc0a43625cbf
I0202 06:22:00.582557 27125 slave.cpp:4328] Got exited event for executor(1)@172.17.0.4:42714
I0202 06:22:00.674434 27114 containerizer.cpp:2482] Container 6665cf7f-b577-42e3-aefc-bc0a43625cbf has exited
I0202 06:22:00.675391 27118 provisioner.cpp:484] Ignoring destroy request for unknown container 6665cf7f-b577-42e3-aefc-bc0a43625cbf
I0202 06:22:00.676321 27118 slave.cpp:4691] Executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 terminated with signal Killed
I0202 06:22:00.676352 27118 slave.cpp:4791] Cleaning up executor '860d6762-e6bd-4be7-8fbd-b758aa025390' of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000 at executor(1)@172.17.0.4:42714
I0202 06:22:00.676595 27114 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390/runs/6665cf7f-b577-42e3-aefc-bc0a43625cbf' for gc 6.99999217035852days in the future
W0202 06:22:00.676656 27124 containerizer.cpp:2107] Attempted to destroy unknown container 6665cf7f-b577-42e3-aefc-bc0a43625cbf.5222d2de-f785-4f54-98ff-a566639d41b8
I0202 06:22:00.676918 27118 slave.cpp:4879] Cleaning up framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.677002 27114 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000/executors/860d6762-e6bd-4be7-8fbd-b758aa025390' for gc 6.99999216607704days in the future
I0202 06:22:00.677016 27124 status_update_manager.cpp:285] Closing status update streams for framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.677150 27124 status_update_manager.cpp:531] Cleaning up status update stream for task 860d6762-e6bd-4be7-8fbd-b758aa025390 of framework 69ede5f4-257e-4afc-a5bc-a1669347de9a-0000
I0202 06:22:00.677165 27114 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_iwbqTu/slaves/69ede5f4-257e-4afc-a5bc-a1669347de9a-S0/frameworks/69ede5f4-257e-4afc-a5bc-a1669347de9a-0000' for gc 6.9999921635763days in the future
I0202 06:22:00.677336 27125 slave.cpp:801] Agent terminating
I0202 06:22:00.677471 27118 master.cpp:1263] Agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4) disconnected
I0202 06:22:00.677496 27118 master.cpp:3117] Disconnecting agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.677561 27118 master.cpp:3136] Deactivating agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 at slave(594)@172.17.0.4:58247 (7f936c7a25f4)
I0202 06:22:00.677666 27126 hierarchical.cpp:579] Agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0 deactivated
I0202 06:22:00.680286 27111 master.cpp:1102] Master terminating
I0202 06:22:00.680625 27113 hierarchical.cpp:512] Removed agent 69ede5f4-257e-4afc-a5bc-a1669347de9a-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1 (400 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0
I0202 06:22:00.685066 27111 cluster.cpp:160] Creating default 'local' authorizer
I0202 06:22:00.686414 27118 master.cpp:383] Master f6922934-8d66-4cd9-8f96-c0c1dd08ac23 (7f936c7a25f4) started on 172.17.0.4:58247
I0202 06:22:00.686437 27118 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/As0FXU/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="/usr/local/share/mesos/webui" --work_dir="/tmp/As0FXU/master" --zk_session_timeout="10secs"
I0202 06:22:00.686703 27118 master.cpp:435] Master only allowing authenticated frameworks to register
I0202 06:22:00.686719 27118 master.cpp:449] Master only allowing authenticated agents to register
I0202 06:22:00.686730 27118 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0202 06:22:00.686744 27118 credentials.hpp:37] Loading credentials for authentication from '/tmp/As0FXU/credentials'
I0202 06:22:00.686929 27118 master.cpp:507] Using default 'crammd5' authenticator
I0202 06:22:00.687006 27118 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0202 06:22:00.687088 27118 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0202 06:22:00.687180 27118 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0202 06:22:00.687314 27118 master.cpp:587] Authorization enabled
I0202 06:22:00.687527 27116 whitelist_watcher.cpp:77] No whitelist given
I0202 06:22:00.688392 27114 hierarchical.cpp:152] Initialized hierarchical allocator process
I0202 06:22:00.688506 27125 master.cpp:2123] Elected as the leading master!
I0202 06:22:00.688527 27125 master.cpp:1645] Recovering from registrar
I0202 06:22:00.688618 27114 registrar.cpp:329] Recovering registrar
I0202 06:22:00.688933 27119 registrar.cpp:362] Successfully fetched the registry (0B) in 263936ns
I0202 06:22:00.688982 27119 registrar.cpp:461] Applied 1 operations in 4620ns; attempting to update the registry
I0202 06:22:00.689314 27112 registrar.cpp:506] Successfully updated the registry in 299008ns
I0202 06:22:00.689381 27112 registrar.cpp:392] Successfully recovered registrar
I0202 06:22:00.689599 27119 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0202 06:22:00.689694 27123 hierarchical.cpp:179] 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-1486013467-5377
Error response from daemon: conflict: unable to remove repository reference "mesos-1486013467-5377" (must force) - container 7f936c7a25f4 is using its referenced image 79e064eb2090
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3213
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3213/changes>