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/01 19:33:50 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3205
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3205/changes>
Changes:
[alexr] Fixed a typo in "scheduler.proto".
[alexr] Augmented comments around (health) check delay in "mesos.proto".
------------------------------------------
[...truncated 147229 lines...]
I0201 15:20:00.462304 24821 hierarchical.cpp:510] Removed agent 09908cbe-595e-41f0-9cc6-a0f10542bcdd-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0 (399 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1
I0201 15:20:00.465863 24813 cluster.cpp:160] Creating default 'local' authorizer
I0201 15:20:00.466732 24828 master.cpp:383] Master 54ee720b-660f-4e8a-9e12-c22899334266 (beaa51dad8f4) started on 172.17.0.3:59159
I0201 15:20:00.466759 24828 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/tohbcS/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/tohbcS/master" --zk_session_timeout="10secs"
I0201 15:20:00.466935 24828 master.cpp:435] Master only allowing authenticated frameworks to register
I0201 15:20:00.466948 24828 master.cpp:449] Master only allowing authenticated agents to register
I0201 15:20:00.466956 24828 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0201 15:20:00.466964 24828 credentials.hpp:37] Loading credentials for authentication from '/tmp/tohbcS/credentials'
I0201 15:20:00.467109 24828 master.cpp:507] Using default 'crammd5' authenticator
I0201 15:20:00.467166 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0201 15:20:00.467236 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0201 15:20:00.467278 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0201 15:20:00.467378 24828 master.cpp:587] Authorization enabled
I0201 15:20:00.467535 24822 hierarchical.cpp:151] Initialized hierarchical allocator process
I0201 15:20:00.467546 24821 whitelist_watcher.cpp:77] No whitelist given
I0201 15:20:00.468176 24828 master.cpp:2123] Elected as the leading master!
I0201 15:20:00.468189 24828 master.cpp:1645] Recovering from registrar
I0201 15:20:00.468240 24828 registrar.cpp:329] Recovering registrar
I0201 15:20:00.468508 24828 registrar.cpp:362] Successfully fetched the registry (0B) in 180992ns
I0201 15:20:00.468538 24828 registrar.cpp:461] Applied 1 operations in 6895ns; attempting to update the registry
I0201 15:20:00.468776 24826 registrar.cpp:506] Successfully updated the registry in 220928ns
I0201 15:20:00.468859 24826 registrar.cpp:392] Successfully recovered registrar
I0201 15:20:00.469033 24818 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0201 15:20:00.469223 24827 hierarchical.cpp:178] Skipping recovery of hierarchical allocator: nothing to recover
I0201 15:20:00.470333 24813 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0201 15:20:00.470582 24813 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0201 15:20:00.470618 24813 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0201 15:20:00.470634 24813 provisioner.cpp:249] Using default backend 'copy'
I0201 15:20:00.471832 24813 cluster.cpp:446] Creating default 'local' authorizer
I0201 15:20:00.472292 24825 slave.cpp:209] Mesos agent started on (590)@172.17.0.3:59159
I0201 15:20:00.472342 24825 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_tfm9Uz/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_tfm9Uz/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_tfm9Uz/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_tfm9Uz" --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_uq0E3I"
I0201 15:20:00.472729 24825 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_tfm9Uz/credential'
I0201 15:20:00.472861 24825 slave.cpp:352] Agent using credential for: test-principal
I0201 15:20:00.472877 24813 sched.cpp:232] Version: 1.2.0
I0201 15:20:00.472931 24825 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_tfm9Uz/http_credentials'
I0201 15:20:00.473095 24819 sched.cpp:336] New master detected at master@172.17.0.3:59159
I0201 15:20:00.473124 24819 sched.cpp:407] Authenticating with master master@172.17.0.3:59159
I0201 15:20:00.473125 24825 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0201 15:20:00.473140 24819 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0201 15:20:00.473209 24825 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0201 15:20:00.473265 24815 authenticatee.cpp:121] Creating new client SASL connection
I0201 15:20:00.473547 24827 master.cpp:7018] Authenticating scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.473644 24820 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1143)@172.17.0.3:59159
I0201 15:20:00.473748 24825 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0201 15:20:00.473779 24820 authenticator.cpp:98] Creating new server SASL connection
I0201 15:20:00.473783 24825 slave.cpp:547] Agent attributes: [ ]
I0201 15:20:00.473829 24825 slave.cpp:552] Agent hostname: beaa51dad8f4
I0201 15:20:00.473879 24818 status_update_manager.cpp:177] Pausing sending status updates
I0201 15:20:00.473925 24820 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0201 15:20:00.473964 24820 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0201 15:20:00.474030 24820 authenticator.cpp:204] Received SASL authentication start
I0201 15:20:00.474082 24820 authenticator.cpp:326] Authentication requires more steps
I0201 15:20:00.474134 24820 authenticatee.cpp:259] Received SASL authentication step
I0201 15:20:00.474187 24820 authenticator.cpp:232] Received SASL authentication step
I0201 15:20:00.474213 24820 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'beaa51dad8f4' server FQDN: 'beaa51dad8f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0201 15:20:00.474230 24820 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0201 15:20:00.474243 24820 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0201 15:20:00.474251 24820 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'beaa51dad8f4' server FQDN: 'beaa51dad8f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0201 15:20:00.474256 24820 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0201 15:20:00.474270 24820 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0201 15:20:00.474288 24820 authenticator.cpp:318] Authentication success
I0201 15:20:00.474335 24820 master.cpp:7048] Successfully authenticated principal 'test-principal' at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.474351 24821 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1143)@172.17.0.3:59159
I0201 15:20:00.474457 24825 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/meta'
I0201 15:20:00.474494 24827 authenticatee.cpp:299] Authentication success
I0201 15:20:00.474704 24819 status_update_manager.cpp:203] Recovering status update manager
I0201 15:20:00.474712 24825 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:59159
I0201 15:20:00.474725 24825 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:59159
I0201 15:20:00.474792 24825 sched.cpp:869] Will retry registration in 786.22151ms if necessary
I0201 15:20:00.474810 24819 containerizer.cpp:599] Recovering containerizer
I0201 15:20:00.474922 24825 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.474941 24825 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0201 15:20:00.475118 24817 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0201 15:20:00.475296 24816 hierarchical.cpp:271] Added framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.475311 24823 sched.cpp:759] Framework registered with 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.475324 24816 hierarchical.cpp:1675] No allocations performed
I0201 15:20:00.475333 24823 sched.cpp:773] Scheduler::registered took 12683ns
I0201 15:20:00.475337 24816 hierarchical.cpp:1770] No inverse offers to send out!
I0201 15:20:00.475349 24816 hierarchical.cpp:1279] Performed allocation for 0 agents in 40466ns
I0201 15:20:00.475399 24826 provisioner.cpp:410] Provisioner recovery complete
I0201 15:20:00.475791 24826 slave.cpp:5422] Finished recovery
I0201 15:20:00.476096 24826 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0201 15:20:00.476223 24817 status_update_manager.cpp:177] Pausing sending status updates
I0201 15:20:00.476253 24814 slave.cpp:929] New master detected at master@172.17.0.3:59159
I0201 15:20:00.476289 24814 slave.cpp:964] Detecting new master
I0201 15:20:00.476440 24814 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0201 15:20:00.478518 24828 slave.cpp:991] Authenticating with master master@172.17.0.3:59159
I0201 15:20:00.478540 24828 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0201 15:20:00.478634 24817 authenticatee.cpp:121] Creating new client SASL connection
I0201 15:20:00.478768 24817 master.cpp:7018] Authenticating slave(590)@172.17.0.3:59159
I0201 15:20:00.478838 24824 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1144)@172.17.0.3:59159
I0201 15:20:00.478960 24818 authenticator.cpp:98] Creating new server SASL connection
I0201 15:20:00.479084 24827 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0201 15:20:00.479111 24827 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0201 15:20:00.479197 24814 authenticator.cpp:204] Received SASL authentication start
I0201 15:20:00.479238 24814 authenticator.cpp:326] Authentication requires more steps
I0201 15:20:00.479284 24814 authenticatee.cpp:259] Received SASL authentication step
I0201 15:20:00.479413 24814 authenticator.cpp:232] Received SASL authentication step
I0201 15:20:00.479434 24814 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'beaa51dad8f4' server FQDN: 'beaa51dad8f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0201 15:20:00.479460 24814 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0201 15:20:00.479476 24814 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0201 15:20:00.479490 24814 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'beaa51dad8f4' server FQDN: 'beaa51dad8f4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0201 15:20:00.479501 24814 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0201 15:20:00.479511 24814 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0201 15:20:00.479527 24814 authenticator.cpp:318] Authentication success
I0201 15:20:00.479580 24827 authenticatee.cpp:299] Authentication success
I0201 15:20:00.479595 24814 master.cpp:7048] Successfully authenticated principal 'test-principal' at slave(590)@172.17.0.3:59159
I0201 15:20:00.479631 24816 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1144)@172.17.0.3:59159
I0201 15:20:00.479725 24827 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:59159
I0201 15:20:00.479816 24827 slave.cpp:1508] Will retry registration in 17.043878ms if necessary
I0201 15:20:00.479954 24819 master.cpp:5303] Registering agent at slave(590)@172.17.0.3:59159 (beaa51dad8f4) with id 54ee720b-660f-4e8a-9e12-c22899334266-S0
I0201 15:20:00.480087 24825 registrar.cpp:461] Applied 1 operations in 18514ns; attempting to update the registry
I0201 15:20:00.480396 24814 registrar.cpp:506] Successfully updated the registry in 286976ns
I0201 15:20:00.480686 24814 slave.cpp:4286] Received ping from slave-observer(537)@172.17.0.3:59159
I0201 15:20:00.480679 24822 master.cpp:5377] Registered agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0201 15:20:00.480769 24814 slave.cpp:1132] Registered with master master@172.17.0.3:59159; given agent ID 54ee720b-660f-4e8a-9e12-c22899334266-S0
I0201 15:20:00.480844 24814 fetcher.cpp:90] Clearing fetcher cache
I0201 15:20:00.480944 24822 status_update_manager.cpp:184] Resuming sending status updates
I0201 15:20:00.481151 24814 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/meta/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/slave.info'
I0201 15:20:00.481400 24814 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0201 15:20:00.481461 24814 master.cpp:5876] Received update of agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4) with total oversubscribed resources {}
I0201 15:20:00.480779 24828 hierarchical.cpp:478] Added agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 (beaa51dad8f4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0201 15:20:00.481916 24828 hierarchical.cpp:1770] No inverse offers to send out!
I0201 15:20:00.481940 24828 hierarchical.cpp:1302] Performed allocation for agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 in 207519ns
I0201 15:20:00.482015 24828 hierarchical.cpp:548] Agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 (beaa51dad8f4) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0201 15:20:00.482065 24828 hierarchical.cpp:1675] No allocations performed
I0201 15:20:00.482077 24828 hierarchical.cpp:1770] No inverse offers to send out!
I0201 15:20:00.482094 24828 hierarchical.cpp:1302] Performed allocation for agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 in 53116ns
I0201 15:20:00.482219 24829 master.cpp:6847] Sending 1 offers to framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.482408 24829 sched.cpp:933] Scheduler::resourceOffers took 55489ns
I0201 15:20:00.482851 24824 master.cpp:3730] Processing ACCEPT call for offers: [ 54ee720b-660f-4e8a-9e12-c22899334266-O0 ] on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4) for framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.482887 24824 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 08c96982-d5a1-4566-a4ce-709d16ff42be
I0201 15:20:00.483376 24824 master.cpp:8902] Adding task 08c96982-d5a1-4566-a4ce-709d16ff42be with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.483456 24824 master.cpp:4380] Launching task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.483722 24821 slave.cpp:1576] Got assigned task '08c96982-d5a1-4566-a4ce-709d16ff42be' for framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.484004 24821 slave.cpp:1736] Launching task '08c96982-d5a1-4566-a4ce-709d16ff42be' for framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.484522 24821 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645' to user 'mesos'
I0201 15:20:00.484694 24821 slave.cpp:6331] Launching executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645'
I0201 15:20:00.484930 24828 containerizer.cpp:992] Starting container a5b0c4ea-657e-460b-8120-a7072b29a645 for executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.484961 24821 slave.cpp:2058] Queued task '08c96982-d5a1-4566-a4ce-709d16ff42be' for executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.485097 24821 slave.cpp:882] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645'
I0201 15:20:00.486336 24820 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.3:59159"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I\/slaves\/54ee720b-660f-4e8a-9e12-c22899334266-S0\/frameworks\/54ee720b-660f-4e8a-9e12-c22899334266-0000\/executors\/08c96982-d5a1-4566-a4ce-709d16ff42be\/runs\/a5b0c4ea-657e-460b-8120-a7072b29a645"},{"name":"MESOS_EXECUTOR_ID","value":"08c96982-d5a1-4566-a4ce-709d16ff42be"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"54ee720b-660f-4e8a-9e12-c22899334266-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"54ee720b-660f-4e8a-9e12-c22899334266-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(590)@172.17.0.3:59159"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I\/slaves\/54ee720b-660f-4e8a-9e12-c22899334266-S0\/frameworks\/54ee720b-660f-4e8a-9e12-c22899334266-0000\/executors\/08c96982-d5a1-4566-a4ce-709d16ff42be\/runs\/a5b0c4ea-657e-460b-8120-a7072b29a645"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I\/slaves\/54ee720b-660f-4e8a-9e12-c22899334266-S0\/frameworks\/54ee720b-660f-4e8a-9e12-c22899334266-0000\/executors\/08c96982-d5a1-4566-a4ce-709d16ff42be\/runs\/a5b0c4ea-657e-460b-8120-a7072b29a645\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I\/slaves\/54ee720b-660f-4e8a-9e12-c22899334266-S0\/frameworks\/54ee720b-660f-4e8a-9e12-c22899334266-0000\/executors\/08c96982-d5a1-4566-a4ce-709d16ff42be\/runs\/a5b0c4ea-657e-460b-8120-a7072b29a645\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I\/slaves\/54ee720b-660f-4e8a-9e12-c22899334266-S0\/frameworks\/54ee720b-660f-4e8a-9e12-c22899334266-0000\/executors\/08c96982-d5a1-4566-a4ce-709d16ff42be\/runs\/a5b0c4ea-657e-460b-8120-a7072b29a645"}" --pipe_read="8" --pipe_write="9" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_tfm9Uz/containers/a5b0c4ea-657e-460b-8120-a7072b29a645" --unshare_namespace_mnt="false"'
I0201 15:20:00.488975 24820 launcher.cpp:135] Forked child with pid '29085' for container 'a5b0c4ea-657e-460b-8120-a7072b29a645'
I0201 15:20:00.491071 24815 fetcher.cpp:349] Starting to fetch URIs for container: a5b0c4ea-657e-460b-8120-a7072b29a645, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645
I0201 15:20:00.593675 24826 slave.cpp:3325] Got registration for executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 from executor(1)@172.17.0.3:43559
I0201 15:20:00.594496 24825 slave.cpp:2271] Sending queued task '08c96982-d5a1-4566-a4ce-709d16ff42be' to executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 at executor(1)@172.17.0.3:43559
I0201 15:20:00.600538 24827 slave.cpp:3756] Handling status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 from executor(1)@172.17.0.3:43559
I0201 15:20:00.601244 24827 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.601267 24827 status_update_manager.cpp:500] Creating StatusUpdate stream for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.601471 24827 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 to the agent
I0201 15:20:00.601675 24829 slave.cpp:4196] Forwarding the update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 to master@172.17.0.3:59159
I0201 15:20:00.601805 24829 slave.cpp:4090] Status update manager successfully handled status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.601835 24829 slave.cpp:4106] Sending acknowledgement for status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 to executor(1)@172.17.0.3:43559
I0201 15:20:00.601992 24829 master.cpp:6021] Status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 from agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.602275 24829 master.cpp:6089] Forwarding status update TASK_RUNNING (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.602491 24829 master.cpp:8157] Updating the state of task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0201 15:20:00.602653 24814 sched.cpp:1041] Scheduler::statusUpdate took 94581ns
I0201 15:20:00.602850 24814 master.cpp:5017] Processing ACKNOWLEDGE call 5bc58bad-9822-4322-902b-30dc53e57f92 for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159 on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0
I0201 15:20:00.603369 24814 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.603544 24814 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: 5bc58bad-9822-4322-902b-30dc53e57f92) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.604269 24828 process.cpp:3697] Handling HTTP event for process 'slave(590)' with path: '/slave(590)/api/v1'
I0201 15:20:00.604606 24828 http.cpp:303] HTTP POST for /slave(590)/api/v1 from 172.17.0.3:41289
I0201 15:20:00.604840 24828 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0201 15:20:00.605151 24821 containerizer.cpp:1808] Starting nested container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.605413 24821 containerizer.cpp:1832] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645/containers/68adcf05-0e14-4e43-a1c8-2c7474e21c60' to user 'mesos'
I0201 15:20:00.606619 24828 switchboard.cpp:434] Allocated pseudo terminal '/dev/pts/1' for container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.606948 24828 switchboard.cpp:571] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-77eb8dd6-a3a1-4f95-b810-65fddab26af8" --stderr_from_fd="12" --stderr_to_fd="2" --stdin_to_fd="12" --stdout_from_fd="12" --stdout_to_fd="1" --tty="true" --wait_for_connection="true"' for container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.609529 24828 switchboard.cpp:601] Created I/O switchboard server (pid: 29105) listening on socket file '/tmp/mesos-io-switchboard-77eb8dd6-a3a1-4f95-b810-65fddab26af8' for container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.610826 24818 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":13,"type":"FD"},"in":{"fd":13,"type":"FD"},"out":{"fd":13,"type":"FD"},"tty_slave_path":"\/dev\/pts\/1","user":"mesos"}" --pipe_read="12" --pipe_write="14" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_tfm9Uz/containers/a5b0c4ea-657e-460b-8120-a7072b29a645/containers/68adcf05-0e14-4e43-a1c8-2c7474e21c60" --unshare_namespace_mnt="false"'
I0201 15:20:00.613253 24818 launcher.cpp:135] Forked child with pid '29106' for container 'a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60'
I0201 15:20:00.614733 24826 fetcher.cpp:349] Starting to fetch URIs for container: a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645/containers/68adcf05-0e14-4e43-a1c8-2c7474e21c60
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(612)@172.17.0.3:59159 that it is currently executing.
I0201 15:20:00.674507 24821 http.cpp:2699] Received EOF attach response for a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
W0201 15:20:00.674835 24823 http.cpp:2714] Launch nested container session connection for container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60 closed
I0201 15:20:00.674875 24821 containerizer.cpp:2119] Destroying container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60 in RUNNING state
I0201 15:20:00.675128 24821 launcher.cpp:151] Asked to destroy container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.675524 24813 sched.cpp:2021] Asked to stop the driver
I0201 15:20:00.675578 24814 sched.cpp:1203] Stopping framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.675815 24824 master.cpp:7560] Processing TEARDOWN call for framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.675946 24824 master.cpp:7572] Removing framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.676053 24824 master.cpp:3080] Deactivating framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (default) at scheduler-4668a6bd-f6e4-471c-8e6f-dcfddc8c36ca@172.17.0.3:59159
I0201 15:20:00.676177 24824 master.cpp:8157] Updating the state of task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0201 15:20:00.676224 24828 hierarchical.cpp:386] Deactivated framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.676287 24816 slave.cpp:2598] Asked to shut down framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 by master@172.17.0.3:59159
I0201 15:20:00.676674 24816 slave.cpp:2623] Shutting down framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.676702 24816 slave.cpp:5005] Shutting down executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 at executor(1)@172.17.0.3:43559
I0201 15:20:00.676995 24824 master.cpp:8251] Removing task 08c96982-d5a1-4566-a4ce-709d16ff42be with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.677376 24822 hierarchical.cpp:1011] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 from framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.677636 24822 hierarchical.cpp:337] Removed framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.679781 24815 containerizer.cpp:2119] Destroying container a5b0c4ea-657e-460b-8120-a7072b29a645 in RUNNING state
I0201 15:20:00.690166 24815 slave.cpp:3756] Handling status update TASK_KILLED (UUID: eaef61a8-640a-4b0c-88d0-950d628f9f16) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 from executor(1)@172.17.0.3:43559
W0201 15:20:00.690208 24815 slave.cpp:3825] Ignoring status update TASK_KILLED (UUID: eaef61a8-640a-4b0c-88d0-950d628f9f16) for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 for terminating framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
E0201 15:20:00.758208 24815 switchboard.cpp:889] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status 1 for container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.758565 24826 containerizer.cpp:2482] Container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60 has exited
I0201 15:20:00.759441 24826 provisioner.cpp:484] Ignoring destroy request for unknown container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.759507 24826 containerizer.cpp:2398] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_tfm9Uz/containers/a5b0c4ea-657e-460b-8120-a7072b29a645/containers/68adcf05-0e14-4e43-a1c8-2c7474e21c60/termination'
I0201 15:20:00.759975 24826 launcher.cpp:151] Asked to destroy container a5b0c4ea-657e-460b-8120-a7072b29a645
I0201 15:20:00.765733 24826 slave.cpp:4328] Got exited event for executor(1)@172.17.0.3:43559
I0201 15:20:00.858743 24828 containerizer.cpp:2482] Container a5b0c4ea-657e-460b-8120-a7072b29a645 has exited
I0201 15:20:00.859612 24819 provisioner.cpp:484] Ignoring destroy request for unknown container a5b0c4ea-657e-460b-8120-a7072b29a645
I0201 15:20:00.860522 24823 slave.cpp:4691] Executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 terminated with signal Killed
I0201 15:20:00.860579 24823 slave.cpp:4791] Cleaning up executor '08c96982-d5a1-4566-a4ce-709d16ff42be' of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000 at executor(1)@172.17.0.3:43559
I0201 15:20:00.860786 24828 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be/runs/a5b0c4ea-657e-460b-8120-a7072b29a645' for gc 6.99999003799704days in the future
I0201 15:20:00.860828 24823 slave.cpp:4879] Cleaning up framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.860858 24828 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000/executors/08c96982-d5a1-4566-a4ce-709d16ff42be' for gc 6.99999003690963days in the future
I0201 15:20:00.860919 24828 status_update_manager.cpp:285] Closing status update streams for framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.860944 24828 status_update_manager.cpp:531] Cleaning up status update stream for task 08c96982-d5a1-4566-a4ce-709d16ff42be of framework 54ee720b-660f-4e8a-9e12-c22899334266-0000
I0201 15:20:00.860954 24823 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_1_uq0E3I/slaves/54ee720b-660f-4e8a-9e12-c22899334266-S0/frameworks/54ee720b-660f-4e8a-9e12-c22899334266-0000' for gc 6.99999003572741days in the future
W0201 15:20:00.861194 24823 containerizer.cpp:2107] Attempted to destroy unknown container a5b0c4ea-657e-460b-8120-a7072b29a645.68adcf05-0e14-4e43-a1c8-2c7474e21c60
I0201 15:20:00.861460 24829 slave.cpp:801] Agent terminating
I0201 15:20:00.861541 24829 master.cpp:1263] Agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4) disconnected
I0201 15:20:00.861559 24829 master.cpp:3117] Disconnecting agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.861587 24829 master.cpp:3136] Deactivating agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 at slave(590)@172.17.0.3:59159 (beaa51dad8f4)
I0201 15:20:00.862038 24829 hierarchical.cpp:577] Agent 54ee720b-660f-4e8a-9e12-c22899334266-S0 deactivated
I0201 15:20:00.863783 24813 master.cpp:1102] Master terminating
I0201 15:20:00.864022 24829 hierarchical.cpp:510] Removed agent 54ee720b-660f-4e8a-9e12-c22899334266-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1 (401 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0
I0201 15:20:00.869155 24813 cluster.cpp:160] Creating default 'local' authorizer
I0201 15:20:00.870095 24828 master.cpp:383] Master e9a307c3-1b9f-4a0e-a538-f55a69204abb (beaa51dad8f4) started on 172.17.0.3:59159
I0201 15:20:00.870127 24828 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/aPyX2i/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/aPyX2i/master" --zk_session_timeout="10secs"
I0201 15:20:00.870306 24828 master.cpp:435] Master only allowing authenticated frameworks to register
I0201 15:20:00.870316 24828 master.cpp:449] Master only allowing authenticated agents to register
I0201 15:20:00.870318 24828 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0201 15:20:00.870323 24828 credentials.hpp:37] Loading credentials for authentication from '/tmp/aPyX2i/credentials'
I0201 15:20:00.870458 24828 master.cpp:507] Using default 'crammd5' authenticator
I0201 15:20:00.870507 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0201 15:20:00.870538 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0201 15:20:00.870558 24828 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0201 15:20:00.870573 24828 master.cpp:587] Authorization enabled
I0201 15:20:00.870898 24825 hierarchical.cpp:151] Initialized hierarchical allocator process
I0201 15:20:00.870970 24825 whitelist_watcher.cpp:77] No whitelist given
I0201 15:20:00.871894 24826 master.cpp:2123] Elected as the leading master!
I0201 15:20:00.871907 24826 master.cpp:1645] Recovering from registrar
I0201 15:20:00.871973 24826 registrar.cpp:329] Recovering registrar
I0201 15:20:00.872381 24820 registrar.cpp:362] Successfully fetched the registry (0B) in 369152ns
I0201 15:20:00.872417 24820 registrar.cpp:461] Applied 1 operations in 9575ns; attempting to update the registry
I0201 15:20:00.872681 24820 registrar.cpp:506] Successfully updated the registry in 247040ns
I0201 15:20:00.872720 24820 registrar.cpp:392] Successfully recovered registrar
I0201 15:20:00.872828 24820 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0201 15:20:00.872887 24820 hierarchical.cpp:178] 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-1485959772-10330
Error response from daemon: conflict: unable to remove repository reference "mesos-1485959772-10330" (must force) - container beaa51dad8f4 is using its referenced image 893d4ae27a07
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3206
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3206/changes>