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/01/16 17:07:14 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3114
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3114/changes>
Changes:
[vinodkone] Ensured master always relinks during scheduler re-registration.
[vinodkone] Added MESOS-6676 to CHANGELOG for 1.0.3.
------------------------------------------
Started by upstream project "Mesos-Buildbot" build number 3114
originally caused by:
Started by an SCM change
Started by an SCM change
[EnvInject] - Loading node environment variables.
Building remotely on H7 (Hadoop) in workspace <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/ws/>
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git --version # timeout=10
> git -c core.askpass=true fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/*
Checking out Revision 64b7a192f101ee6bb81a58222c10aeda2250aae7 (origin/1.0.x)
> git config core.sparsecheckout # timeout=10
> git checkout -f 64b7a192f101ee6bb81a58222c10aeda2250aae7
> git rev-list c82a283d87af557128a96ec02f2ef9a00f00d4df # timeout=10
[(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)] $ /bin/bash -xe /tmp/hudson1739762455450704234.sh
+ ./support/docker-build.sh
/tmp/hudson1739762455450704234.sh: line 2: ./support/docker-build.sh: No such file or directory
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3116
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3116/>
Build failed in Jenkins: Mesos-Buildbot » autotools,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3115
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3115/changes>
Changes:
[haosdent] Extended copyright in website footer to 2017.
[vinodkone] Updated CHANGELOG for 1.0.3.
------------------------------------------
[...truncated 153321 lines...]
I0116 17:47:04.369967 28477 master.cpp:1100] Master terminating
I0116 17:47:04.370801 28475 hierarchical.cpp:510] Removed agent 7ac3c070-2612-49f2-bc6b-0a9fae7b7ac7-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/1 (604 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0
I0116 17:47:04.379883 28449 cluster.cpp:160] Creating default 'local' authorizer
I0116 17:47:04.382339 28469 master.cpp:383] Master 1c230966-00b4-473e-b74c-0ee3b8c2da44 (02cd00675596) started on 172.17.0.3:45396
I0116 17:47:04.382395 28469 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/z1hYtM/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/z1hYtM/master" --zk_session_timeout="10secs"
I0116 17:47:04.382961 28469 master.cpp:435] Master only allowing authenticated frameworks to register
I0116 17:47:04.382982 28469 master.cpp:449] Master only allowing authenticated agents to register
I0116 17:47:04.382997 28469 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0116 17:47:04.383010 28469 credentials.hpp:37] Loading credentials for authentication from '/tmp/z1hYtM/credentials'
I0116 17:47:04.383504 28469 master.cpp:507] Using default 'crammd5' authenticator
I0116 17:47:04.383673 28469 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0116 17:47:04.383833 28469 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0116 17:47:04.383952 28469 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0116 17:47:04.384059 28469 master.cpp:587] Authorization enabled
I0116 17:47:04.384413 28477 whitelist_watcher.cpp:77] No whitelist given
I0116 17:47:04.384541 28474 hierarchical.cpp:151] Initialized hierarchical allocator process
I0116 17:47:04.389894 28474 master.cpp:2119] Elected as the leading master!
I0116 17:47:04.389955 28474 master.cpp:1641] Recovering from registrar
I0116 17:47:04.390125 28467 registrar.cpp:329] Recovering registrar
I0116 17:47:04.390868 28467 registrar.cpp:362] Successfully fetched the registry (0B) in 609280ns
I0116 17:47:04.391024 28467 registrar.cpp:461] Applied 1 operations in 52365ns; attempting to update the registry
I0116 17:47:04.392161 28480 registrar.cpp:506] Successfully updated the registry in 1.051904ms
I0116 17:47:04.392321 28480 registrar.cpp:392] Successfully recovered registrar
I0116 17:47:04.392849 28482 master.cpp:1757] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0116 17:47:04.392874 28481 hierarchical.cpp:178] Skipping recovery of hierarchical allocator: nothing to recover
I0116 17:47:04.395752 28449 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0116 17:47:04.396422 28449 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W0116 17:47:04.396687 28449 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0116 17:47:04.400326 28449 cluster.cpp:446] Creating default 'local' authorizer
I0116 17:47:04.402330 28482 slave.cpp:209] Mesos agent started on (586)@172.17.0.3:45396
I0116 17:47:04.402382 28482 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_0_o3DjRS/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_0_o3DjRS/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_0_o3DjRS/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS" --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_0_HphDFP"
I0116 17:47:04.403408 28482 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS/credential'
I0116 17:47:04.403728 28482 slave.cpp:352] Agent using credential for: test-principal
I0116 17:47:04.403848 28482 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS/http_credentials'
I0116 17:47:04.404019 28449 sched.cpp:232] Version: 1.2.0
I0116 17:47:04.404355 28482 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0116 17:47:04.404656 28482 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0116 17:47:04.404846 28476 sched.cpp:336] New master detected at master@172.17.0.3:45396
I0116 17:47:04.405088 28476 sched.cpp:407] Authenticating with master master@172.17.0.3:45396
I0116 17:47:04.405261 28476 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0116 17:47:04.405665 28481 authenticatee.cpp:121] Creating new client SASL connection
I0116 17:47:04.405956 28480 master.cpp:6833] Authenticating scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.406059 28481 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1129)@172.17.0.3:45396
I0116 17:47:04.406358 28480 authenticator.cpp:98] Creating new server SASL connection
I0116 17:47:04.406363 28482 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0116 17:47:04.406570 28482 slave.cpp:547] Agent attributes: [ ]
I0116 17:47:04.406594 28481 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0116 17:47:04.406700 28481 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0116 17:47:04.406677 28482 slave.cpp:552] Agent hostname: 02cd00675596
I0116 17:47:04.406800 28481 authenticator.cpp:204] Received SASL authentication start
I0116 17:47:04.406966 28481 authenticator.cpp:326] Authentication requires more steps
I0116 17:47:04.407063 28481 authenticatee.cpp:259] Received SASL authentication step
I0116 17:47:04.407166 28481 authenticator.cpp:232] Received SASL authentication step
I0116 17:47:04.407205 28481 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02cd00675596' server FQDN: '02cd00675596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0116 17:47:04.407229 28481 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0116 17:47:04.407327 28481 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0116 17:47:04.407368 28481 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02cd00675596' server FQDN: '02cd00675596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0116 17:47:04.407389 28481 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0116 17:47:04.407402 28481 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0116 17:47:04.407423 28481 authenticator.cpp:318] Authentication success
I0116 17:47:04.407626 28481 authenticatee.cpp:299] Authentication success
I0116 17:47:04.407717 28481 master.cpp:6863] Successfully authenticated principal 'test-principal' at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.407817 28481 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1129)@172.17.0.3:45396
I0116 17:47:04.408157 28467 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:45396
I0116 17:47:04.408184 28467 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:45396
I0116 17:47:04.408331 28467 sched.cpp:869] Will retry registration in 1.275538653secs if necessary
I0116 17:47:04.408465 28481 master.cpp:2707] Received SUBSCRIBE call for framework 'default' at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.408495 28481 master.cpp:2155] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0116 17:47:04.408953 28468 master.cpp:2783] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0116 17:47:04.409499 28468 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/meta'
I0116 17:47:04.409704 28470 sched.cpp:759] Framework registered with 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.409880 28482 status_update_manager.cpp:203] Recovering status update manager
I0116 17:47:04.409945 28470 sched.cpp:773] Scheduler::registered took 73222ns
I0116 17:47:04.410341 28468 containerizer.cpp:599] Recovering containerizer
I0116 17:47:04.410351 28467 hierarchical.cpp:271] Added framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.410511 28467 hierarchical.cpp:1677] No allocations performed
I0116 17:47:04.410567 28467 hierarchical.cpp:1772] No inverse offers to send out!
I0116 17:47:04.410642 28467 hierarchical.cpp:1279] Performed allocation for 0 agents in 252807ns
I0116 17:47:04.411834 28471 provisioner.cpp:251] Provisioner recovery complete
I0116 17:47:04.412142 28478 slave.cpp:5417] Finished recovery
I0116 17:47:04.412771 28478 slave.cpp:5591] Querying resource estimator for oversubscribable resources
I0116 17:47:04.413036 28474 slave.cpp:924] New master detected at master@172.17.0.3:45396
I0116 17:47:04.413188 28474 slave.cpp:959] Detecting new master
I0116 17:47:04.413462 28473 status_update_manager.cpp:177] Pausing sending status updates
I0116 17:47:04.413554 28474 slave.cpp:5605] Received oversubscribable resources {} from the resource estimator
I0116 17:47:04.415573 28480 slave.cpp:986] Authenticating with master master@172.17.0.3:45396
I0116 17:47:04.415710 28480 slave.cpp:997] Using default CRAM-MD5 authenticatee
I0116 17:47:04.415992 28475 authenticatee.cpp:121] Creating new client SASL connection
I0116 17:47:04.416311 28477 master.cpp:6833] Authenticating slave(586)@172.17.0.3:45396
I0116 17:47:04.416440 28479 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1130)@172.17.0.3:45396
I0116 17:47:04.416700 28475 authenticator.cpp:98] Creating new server SASL connection
I0116 17:47:04.416947 28474 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0116 17:47:04.416992 28474 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0116 17:47:04.417286 28480 authenticator.cpp:204] Received SASL authentication start
I0116 17:47:04.417466 28480 authenticator.cpp:326] Authentication requires more steps
I0116 17:47:04.417618 28480 authenticatee.cpp:259] Received SASL authentication step
I0116 17:47:04.417752 28480 authenticator.cpp:232] Received SASL authentication step
I0116 17:47:04.417839 28480 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02cd00675596' server FQDN: '02cd00675596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0116 17:47:04.417860 28480 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0116 17:47:04.417932 28480 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0116 17:47:04.417971 28480 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02cd00675596' server FQDN: '02cd00675596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0116 17:47:04.417990 28480 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0116 17:47:04.418004 28480 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0116 17:47:04.418059 28480 authenticator.cpp:318] Authentication success
I0116 17:47:04.418181 28474 authenticatee.cpp:299] Authentication success
I0116 17:47:04.418498 28479 slave.cpp:1081] Successfully authenticated with master master@172.17.0.3:45396
I0116 17:47:04.418535 28475 master.cpp:6863] Successfully authenticated principal 'test-principal' at slave(586)@172.17.0.3:45396
I0116 17:47:04.418586 28477 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1130)@172.17.0.3:45396
I0116 17:47:04.418900 28479 slave.cpp:1503] Will retry registration in 72130ns if necessary
I0116 17:47:04.419148 28473 master.cpp:5232] Registering agent at slave(586)@172.17.0.3:45396 (02cd00675596) with id 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0
I0116 17:47:04.419567 28480 registrar.cpp:461] Applied 1 operations in 84190ns; attempting to update the registry
I0116 17:47:04.420406 28472 slave.cpp:1503] Will retry registration in 21.492005ms if necessary
I0116 17:47:04.420677 28477 master.cpp:5220] Ignoring register agent message from slave(586)@172.17.0.3:45396 (02cd00675596) as admission is already in progress
I0116 17:47:04.421108 28480 registrar.cpp:506] Successfully updated the registry in 1.455872ms
I0116 17:47:04.421913 28474 slave.cpp:4281] Received ping from slave-observer(533)@172.17.0.3:45396
I0116 17:47:04.422153 28468 slave.cpp:1127] Registered with master master@172.17.0.3:45396; given agent ID 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0
I0116 17:47:04.422072 28477 master.cpp:5303] Registered agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0116 17:47:04.422372 28480 hierarchical.cpp:478] Added agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 (02cd00675596) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0116 17:47:04.422441 28468 fetcher.cpp:90] Clearing fetcher cache
I0116 17:47:04.422705 28475 status_update_manager.cpp:184] Resuming sending status updates
I0116 17:47:04.422973 28468 slave.cpp:1155] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/meta/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/slave.info'
I0116 17:47:04.423408 28468 slave.cpp:1193] Forwarding total oversubscribed resources {}
I0116 17:47:04.423447 28480 hierarchical.cpp:1772] No inverse offers to send out!
I0116 17:47:04.423542 28480 hierarchical.cpp:1302] Performed allocation for agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 in 1.079437ms
I0116 17:47:04.423933 28471 master.cpp:6662] Sending 1 offers to framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.424165 28471 master.cpp:5710] Received update of agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596) with total oversubscribed resources {}
I0116 17:47:04.424448 28468 hierarchical.cpp:548] Agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 (02cd00675596) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0116 17:47:04.424504 28470 sched.cpp:933] Scheduler::resourceOffers took 154170ns
I0116 17:47:04.425031 28468 hierarchical.cpp:1677] No allocations performed
I0116 17:47:04.425083 28468 hierarchical.cpp:1772] No inverse offers to send out!
I0116 17:47:04.425262 28468 hierarchical.cpp:1302] Performed allocation for agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 in 519687ns
I0116 17:47:04.426398 28482 master.cpp:3661] Processing ACCEPT call for offers: [ 1c230966-00b4-473e-b74c-0ee3b8c2da44-O0 ] on agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596) for framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.426517 28482 master.cpp:3249] Authorizing framework principal 'test-principal' to launch task b54dc93e-67fb-432b-a2d0-dc4195f919c1
I0116 17:47:04.428877 28473 master.cpp:8575] Adding task b54dc93e-67fb-432b-a2d0-dc4195f919c1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.429229 28473 master.cpp:4311] Launching task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.429926 28478 slave.cpp:1571] Got assigned task 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' for framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.430589 28478 slave.cpp:1731] Launching task 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' for framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.431201 28478 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287' to user 'mesos'
I0116 17:47:04.438768 28478 slave.cpp:6345] Launching executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287'
I0116 17:47:04.439635 28467 containerizer.cpp:991] Starting container 674dc08e-570c-4a63-a022-216305364287 for executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.439775 28478 slave.cpp:2053] Queued task 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' for executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.439885 28478 slave.cpp:877] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287'
I0116 17:47:04.445610 28477 containerizer.cpp:1540] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.2.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.2.0\/_build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.3:45396"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP\/slaves\/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0\/frameworks\/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000\/executors\/b54dc93e-67fb-432b-a2d0-dc4195f919c1\/runs\/674dc08e-570c-4a63-a022-216305364287"},{"name":"MESOS_EXECUTOR_ID","value":"b54dc93e-67fb-432b-a2d0-dc4195f919c1"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"1c230966-00b4-473e-b74c-0ee3b8c2da44-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"1c230966-00b4-473e-b74c-0ee3b8c2da44-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(586)@172.17.0.3:45396"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP\/slaves\/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0\/frameworks\/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000\/executors\/b54dc93e-67fb-432b-a2d0-dc4195f919c1\/runs\/674dc08e-570c-4a63-a022-216305364287"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP\/slaves\/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0\/frameworks\/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000\/executors\/b54dc93e-67fb-432b-a2d0-dc4195f919c1\/runs\/674dc08e-570c-4a63-a022-216305364287\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP\/slaves\/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0\/frameworks\/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000\/executors\/b54dc93e-67fb-432b-a2d0-dc4195f919c1\/runs\/674dc08e-570c-4a63-a022-216305364287\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP\/slaves\/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0\/frameworks\/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000\/executors\/b54dc93e-67fb-432b-a2d0-dc4195f919c1\/runs\/674dc08e-570c-4a63-a022-216305364287"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS/containers/674dc08e-570c-4a63-a022-216305364287" --unshare_namespace_mnt="false"'
I0116 17:47:04.449014 28477 launcher.cpp:133] Forked child with pid '8977' for container '674dc08e-570c-4a63-a022-216305364287'
I0116 17:47:04.452888 28480 fetcher.cpp:349] Starting to fetch URIs for container: 674dc08e-570c-4a63-a022-216305364287, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287
I0116 17:47:04.624728 28479 slave.cpp:3320] Got registration for executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 from executor(1)@172.17.0.3:50694
I0116 17:47:04.627215 28472 slave.cpp:2266] Sending queued task 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' to executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 at executor(1)@172.17.0.3:50694
I0116 17:47:04.642421 28473 slave.cpp:3751] Handling status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 from executor(1)@172.17.0.3:50694
I0116 17:47:04.645179 28471 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.645263 28471 status_update_manager.cpp:500] Creating StatusUpdate stream for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.645985 28471 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 to the agent
I0116 17:47:04.646466 28469 slave.cpp:4191] Forwarding the update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 to master@172.17.0.3:45396
I0116 17:47:04.646930 28472 master.cpp:5846] Status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 from agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.646997 28472 master.cpp:5908] Forwarding status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.647012 28471 slave.cpp:4085] Status update manager successfully handled status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.647073 28471 slave.cpp:4101] Sending acknowledgement for status update TASK_RUNNING (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 to executor(1)@172.17.0.3:50694
I0116 17:47:04.647415 28472 master.cpp:7947] Updating the state of task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0116 17:47:04.647778 28481 sched.cpp:1041] Scheduler::statusUpdate took 201567ns
I0116 17:47:04.648160 28481 master.cpp:4948] Processing ACKNOWLEDGE call 28b86ac4-615a-43a5-98cf-e22eb4bf10f5 for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396 on agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0
I0116 17:47:04.649030 28478 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.649601 28478 slave.cpp:3040] Status update manager successfully handled status update acknowledgement (UUID: 28b86ac4-615a-43a5-98cf-e22eb4bf10f5) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.651744 28471 process.cpp:3676] Handling HTTP event for process 'slave(586)' with path: '/slave(586)/api/v1'
I0116 17:47:04.653429 28471 http.cpp:303] HTTP POST for /slave(586)/api/v1 from 172.17.0.3:49030
I0116 17:47:04.654131 28471 http.cpp:464] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0116 17:47:04.654736 28473 containerizer.cpp:1807] Starting nested container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.655282 28473 containerizer.cpp:1831] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287/containers/d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf' to user 'mesos'
I0116 17:47:04.667676 28476 switchboard.cpp:433] Allocated pseudo terminal '/dev/pts/1' for container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.668236 28476 switchboard.cpp:570] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-6a64c3a1-00bb-48e4-b512-3f81d27cd9d1" --stderr_from_fd="28" --stderr_to_fd="2" --stdin_to_fd="28" --stdout_from_fd="28" --stdout_to_fd="1" --tty="true" --wait_for_connection="true"' for container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.673100 28476 switchboard.cpp:600] Created I/O switchboard server (pid: 9038) listening on socket file '/tmp/mesos-io-switchboard-6a64c3a1-00bb-48e4-b512-3f81d27cd9d1' for container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.675608 28467 containerizer.cpp:1540] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{"fd":29,"type":"FD"},"in":{"fd":29,"type":"FD"},"out":{"fd":29,"type":"FD"},"tty_slave_path":"\/dev\/pts\/1","user":"mesos"}" --pipe_read="28" --pipe_write="40" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS/containers/674dc08e-570c-4a63-a022-216305364287/containers/d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf" --unshare_namespace_mnt="false"'
I0116 17:47:04.680781 28467 launcher.cpp:133] Forked child with pid '9040' for container '674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf'
I0116 17:47:04.685030 28478 fetcher.cpp:349] Starting to fetch URIs for container: 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287/containers/d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(607)@172.17.0.3:45396 that it is currently executing.
I0116 17:47:04.776417 28481 http.cpp:2708] Received EOF attach response for 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.776737 28481 containerizer.cpp:2118] Destroying container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf in RUNNING state
W0116 17:47:04.776991 28480 http.cpp:2723] Launch nested container session connection for container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf closed
I0116 17:47:04.777242 28481 launcher.cpp:149] Asked to destroy container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.778287 28449 sched.cpp:2021] Asked to stop the driver
I0116 17:47:04.778462 28477 sched.cpp:1203] Stopping framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.778885 28476 master.cpp:7371] Processing TEARDOWN call for framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.779052 28476 master.cpp:7383] Removing framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (default) at scheduler-2cad3f2b-6194-4487-a722-d0ae910dd0a6@172.17.0.3:45396
I0116 17:47:04.779454 28476 master.cpp:7947] Updating the state of task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0116 17:47:04.780463 28476 master.cpp:8043] Removing task b54dc93e-67fb-432b-a2d0-dc4195f919c1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 on agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.781230 28468 hierarchical.cpp:386] Deactivated framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.781566 28479 slave.cpp:2593] Asked to shut down framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 by master@172.17.0.3:45396
I0116 17:47:04.781610 28479 slave.cpp:2618] Shutting down framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.781675 28479 slave.cpp:5000] Shutting down executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 at executor(1)@172.17.0.3:50694
I0116 17:47:04.783062 28468 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 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 from framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.783411 28468 hierarchical.cpp:337] Removed framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.785169 28476 containerizer.cpp:2118] Destroying container 674dc08e-570c-4a63-a022-216305364287 in RUNNING state
I0116 17:47:04.814128 28468 slave.cpp:3751] Handling status update TASK_KILLED (UUID: 42e9a5c7-4edd-4b05-9a72-7affd1262bdb) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 from executor(1)@172.17.0.3:50694
W0116 17:47:04.814234 28468 slave.cpp:3820] Ignoring status update TASK_KILLED (UUID: 42e9a5c7-4edd-4b05-9a72-7affd1262bdb) for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 for terminating framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
E0116 17:47:04.863621 28473 switchboard.cpp:886] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status: 1 for container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.864367 28472 containerizer.cpp:2481] Container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf has exited
I0116 17:47:04.867424 28479 provisioner.cpp:322] Ignoring destroy request for unknown container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.867609 28479 containerizer.cpp:2397] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_o3DjRS/containers/674dc08e-570c-4a63-a022-216305364287/containers/d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf/termination'
I0116 17:47:04.868650 28479 launcher.cpp:149] Asked to destroy container 674dc08e-570c-4a63-a022-216305364287
I0116 17:47:04.879405 28477 slave.cpp:4323] Got exited event for executor(1)@172.17.0.3:50694
I0116 17:47:04.965865 28480 containerizer.cpp:2481] Container 674dc08e-570c-4a63-a022-216305364287 has exited
I0116 17:47:04.968121 28480 provisioner.cpp:322] Ignoring destroy request for unknown container 674dc08e-570c-4a63-a022-216305364287
I0116 17:47:04.969146 28477 slave.cpp:4686] Executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 terminated with signal Killed
I0116 17:47:04.969239 28477 slave.cpp:4786] Cleaning up executor 'b54dc93e-67fb-432b-a2d0-dc4195f919c1' of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000 at executor(1)@172.17.0.3:50694
I0116 17:47:04.969820 28477 slave.cpp:4874] Cleaning up framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.969817 28482 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1/runs/674dc08e-570c-4a63-a022-216305364287' for gc 6.99998877883259days in the future
W0116 17:47:04.969938 28474 containerizer.cpp:2106] Attempted to destroy unknown container 674dc08e-570c-4a63-a022-216305364287.d6ed62d2-3286-4ea9-b3b8-7a078a62c8cf
I0116 17:47:04.970115 28482 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000/executors/b54dc93e-67fb-432b-a2d0-dc4195f919c1' for gc 6.99998877591704days in the future
I0116 17:47:04.970194 28472 status_update_manager.cpp:285] Closing status update streams for framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.970432 28472 status_update_manager.cpp:531] Cleaning up status update stream for task b54dc93e-67fb-432b-a2d0-dc4195f919c1 of framework 1c230966-00b4-473e-b74c-0ee3b8c2da44-0000
I0116 17:47:04.970314 28469 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_HphDFP/slaves/1c230966-00b4-473e-b74c-0ee3b8c2da44-S0/frameworks/1c230966-00b4-473e-b74c-0ee3b8c2da44-0000' for gc 6.99998877058963days in the future
I0116 17:47:04.971040 28477 slave.cpp:796] Agent terminating
I0116 17:47:04.971257 28482 master.cpp:1261] Agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596) disconnected
I0116 17:47:04.971294 28482 master.cpp:3051] Disconnecting agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.971352 28482 master.cpp:3070] Deactivating agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 at slave(586)@172.17.0.3:45396 (02cd00675596)
I0116 17:47:04.971524 28482 hierarchical.cpp:577] Agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0 deactivated
I0116 17:47:04.976090 28477 master.cpp:1100] Master terminating
I0116 17:47:04.977294 28481 hierarchical.cpp:510] Removed agent 1c230966-00b4-473e-b74c-0ee3b8c2da44-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0 (603 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1
I0116 17:47:04.985044 28449 cluster.cpp:160] Creating default 'local' authorizer
I0116 17:47:04.987452 28479 master.cpp:383] Master eb377ca0-944b-4829-8aea-17c9e4506022 (02cd00675596) started on 172.17.0.3:45396
I0116 17:47:04.987481 28479 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/PnfCvr/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/PnfCvr/master" --zk_session_timeout="10secs"
I0116 17:47:04.987916 28479 master.cpp:435] Master only allowing authenticated frameworks to register
I0116 17:47:04.987931 28479 master.cpp:449] Master only allowing authenticated agents to register
I0116 17:47:04.987944 28479 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0116 17:47:04.987957 28479 credentials.hpp:37] Loading credentials for authentication from '/tmp/PnfCvr/credentials'
I0116 17:47:04.988234 28479 master.cpp:507] Using default 'crammd5' authenticator
I0116 17:47:04.988358 28479 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0116 17:47:04.988548 28479 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0116 17:47:04.988721 28479 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0116 17:47:04.988926 28479 master.cpp:587] Authorization enabled
I0116 17:47:04.989141 28472 whitelist_watcher.cpp:77] No whitelist given
I0116 17:47:04.989192 28468 hierarchical.cpp:151] Initialized hierarchical allocator process
I0116 17:47:04.991363 28479 master.cpp:2119] Elected as the leading master!
I0116 17:47:04.991389 28479 master.cpp:1641] Recovering from registrar
I0116 17:47:04.991612 28479 registrar.cpp:329] Recovering registrar
I0116 17:47:04.992252 28479 registrar.cpp:362] Successfully fetched the registry (0B) in 602112ns
I0116 17:47:04.992367 28479 registrar.cpp:461] Applied 1 operations in 35308ns; attempting to update the registry
I0116 17:47:04.993124 28479 registrar.cpp:506] Successfully updated the registry in 696832ns
I0116 17:47:04.993335 28479 registrar.cpp:392] Successfully recovered registrar
I0116 17:47:04.993876 28480 master.cpp:1757] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0116 17:47:04.993921 28479 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-1484586468-5929
Error response from daemon: conflict: unable to remove repository reference "mesos-1484586468-5929" (must force) - container 02cd00675596 is using its referenced image 8988d48263b8