Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm
Precedence: bulk
Reply-To: builds@mesos.apache.org
Date: Sun, 29 Jan 2017 06:06:02 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: builds@mesos.apache.org, yujie.jay@gmail.com
Message-ID: <649290178.5361.1485669963918.JavaMail.jenkins@crius>
Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos-Buildbo?=
 =?UTF-8?Q?t_=C2=BB_autotools,gcc,--verbose,GLOG=5Fv=3D1_M?=
 =?UTF-8?Q?ESOS=5FVERBOSE=3D1,centos:7,(docker||Hadoo?=
 =?UTF-8?Q?p)&&(!ubuntu-us1)&&(!ubuntu-eu2)_#3201?=
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: quoted-printable
archived-at: Sun, 29 Jan 2017 06:06:07 -0000

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=3Dautotools,COM=
PILER=3Dgcc,CONFIGURATION=3D--verbose,ENVIRONMENT=3DGLOG_v=3D1%20MESOS_VERB=
OSE=3D1,OS=3Dcentos%3A7,label_exp=3D(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!=
ubuntu-eu2)/3201/changes>

Changes:

[yujie.jay] Fixed duplicate image layer ids returned by docker store.

------------------------------------------
[...truncated 154948 lines...]
I0129 01:47:06.737426 31205 master.cpp:3136] Deactivating agent c63921a3-ff=
c1-4b52-b055-e7d44d4367d1-S0 at slave(587)@172.17.0.2:52605 (528a1b6e6459)
I0129 01:47:06.737676 31205 hierarchical.cpp:577] Agent c63921a3-ffc1-4b52-=
b055-e7d44d4367d1-S0 deactivated
I0129 01:47:06.744351 31207 master.cpp:1102] Master terminating
I0129 01:47:06.745267 31211 hierarchical.cpp:510] Removed agent c63921a3-ff=
c1-4b52-b055-e7d44d4367d1-S0
[       OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/1 (607 m=
s)
[ RUN      ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0
I0129 01:47:06.754794 31182 cluster.cpp:160] Creating default 'local' autho=
rizer
I0129 01:47:06.758325 31208 master.cpp:383] Master 525fac7c-347d-4045-bbe0-=
8853f70b0864 (528a1b6e6459) started on 172.17.0.2:52605
I0129 01:47:06.758352 31208 master.cpp:385] Flags at startup: --acls=3D"" -=
-agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo=
cation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authenticate_ag=
ents=3D"true" --authenticate_frameworks=3D"true" --authenticate_http_framew=
orks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_rea=
dwrite=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local" --cred=
entials=3D"/tmp/uytwMM/credentials" --framework_sorter=3D"drf" --help=3D"fa=
lse" --hostname_lookup=3D"true" --http_authenticators=3D"basic" --http_fram=
ework_authenticators=3D"basic" --initialize_driver_logging=3D"true" --log_a=
uto_initialize=3D"true" --logbufsecs=3D"0" --logging_level=3D"INFO" --max_a=
gent_ping_timeouts=3D"5" --max_completed_frameworks=3D"50" --max_completed_=
tasks_per_framework=3D"1000" --max_unreachable_tasks_per_framework=3D"1000"=
 --quiet=3D"false" --recovery_agent_removal_limit=3D"100%" --registry=3D"in=
_memory" --registry_fetch_timeout=3D"1mins" --registry_gc_interval=3D"15min=
s" --registry_max_agent_age=3D"2weeks" --registry_max_agent_count=3D"102400=
" --registry_store_timeout=3D"100secs" --registry_strict=3D"false" --root_s=
ubmissions=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_dir=
=3D"/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir=3D"/tmp/uytwMM/m=
aster" --zk_session_timeout=3D"10secs"
I0129 01:47:06.758709 31208 master.cpp:435] Master only allowing authentica=
ted frameworks to register
I0129 01:47:06.758723 31208 master.cpp:449] Master only allowing authentica=
ted agents to register
I0129 01:47:06.758728 31208 master.cpp:462] Master only allowing authentica=
ted HTTP frameworks to register
I0129 01:47:06.758735 31208 credentials.hpp:37] Loading credentials for aut=
hentication from '/tmp/uytwMM/credentials'
I0129 01:47:06.758997 31208 master.cpp:507] Using default 'crammd5' authent=
icator
I0129 01:47:06.759146 31208 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-readonly'
I0129 01:47:06.759323 31208 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-readwrite'
I0129 01:47:06.759435 31208 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-scheduler'
I0129 01:47:06.759572 31208 master.cpp:587] Authorization enabled
I0129 01:47:06.759949 31211 hierarchical.cpp:151] Initialized hierarchical =
allocator process
I0129 01:47:06.760030 31211 whitelist_watcher.cpp:77] No whitelist given
I0129 01:47:06.762802 31211 master.cpp:2123] Elected as the leading master!
I0129 01:47:06.762861 31211 master.cpp:1645] Recovering from registrar
I0129 01:47:06.762996 31203 registrar.cpp:329] Recovering registrar
I0129 01:47:06.763639 31206 registrar.cpp:362] Successfully fetched the reg=
istry (0B) in 588032ns
I0129 01:47:06.763758 31206 registrar.cpp:461] Applied 1 operations in 2236=
3ns; attempting to update the registry
I0129 01:47:06.764998 31206 registrar.cpp:506] Successfully updated the reg=
istry in 1.168896ms
I0129 01:47:06.765153 31206 registrar.cpp:392] Successfully recovered regis=
trar
I0129 01:47:06.765753 31213 master.cpp:1761] Recovered 0 agents from the re=
gistry (129B); allowing 10mins for agents to re-register
I0129 01:47:06.765774 31211 hierarchical.cpp:178] Skipping recovery of hier=
archical allocator: nothing to recover
I0129 01:47:06.769099 31182 containerizer.cpp:220] Using isolation: posix/c=
pu,posix/mem,filesystem/posix,network/cni
W0129 01:47:06.769610 31182 backend.cpp:76] Failed to create 'aufs' backend=
: AufsBackend requires root privileges
W0129 01:47:06.769742 31182 backend.cpp:76] Failed to create 'bind' backend=
: BindBackend requires root privileges
I0129 01:47:06.773351 31182 cluster.cpp:446] Creating default 'local' autho=
rizer
I0129 01:47:06.775411 31214 slave.cpp:209] Mesos agent started on (588)@172=
.17.0.2:52605
I0129 01:47:06.775449 31214 slave.cpp:210] Flags at startup: --acls=3D"" --=
appc_simple_discovery_uri_prefix=3D"http://" --appc_store_dir=3D"/tmp/mesos=
/store/appc" --authenticate_http_readonly=3D"true" --authenticate_http_read=
write=3D"true" --authenticatee=3D"crammd5" --authentication_backoff_factor=
=3D"1secs" --authorizer=3D"local" --cgroups_cpu_enable_pids_and_tids_count=
=3D"false" --cgroups_enable_cfs=3D"false" --cgroups_hierarchy=3D"/sys/fs/cg=
roup" --cgroups_limit_swap=3D"false" --cgroups_root=3D"mesos" --container_d=
isk_watch_interval=3D"15secs" --containerizers=3D"mesos" --credential=3D"/t=
mp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_oCWVvz/cr=
edential" --default_role=3D"*" --disk_watch_interval=3D"1mins" --docker=3D"=
docker" --docker_kill_orphans=3D"true" --docker_registry=3D"https://registr=
y-1.docker.io" --docker_remove_delay=3D"6hrs" --docker_socket=3D"/var/run/d=
ocker.sock" --docker_stop_timeout=3D"0ns" --docker_store_dir=3D"/tmp/mesos/=
store/docker" --docker_volume_checkpoint_dir=3D"/var/run/mesos/isolators/do=
cker/volume" --enforce_container_disk_quota=3D"false" --executor_registrati=
on_timeout=3D"1mins" --executor_shutdown_grace_period=3D"5secs" --fetcher_c=
ache_dir=3D"/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithT=
TY_0_oCWVvz/fetch" --fetcher_cache_size=3D"2GB" --frameworks_home=3D"" --gc=
_delay=3D"1weeks" --gc_disk_headroom=3D"0.1" --hadoop_home=3D"" --help=3D"f=
alse" --hostname_lookup=3D"true" --http_authenticators=3D"basic" --http_com=
mand_executor=3D"false" --http_credentials=3D"/tmp/ContentType_AgentAPITest=
_LaunchNestedContainerSessionWithTTY_0_oCWVvz/http_credentials" --http_hear=
tbeat_interval=3D"30secs" --image_provisioner_backend=3D"copy" --initialize=
_driver_logging=3D"true" --isolation=3D"posix/cpu,posix/mem" --launcher=3D"=
posix" --launcher_dir=3D"/mesos/mesos-1.2.0/_build/src" --logbufsecs=3D"0" =
--logging_level=3D"INFO" --max_completed_executors_per_framework=3D"150" --=
oversubscribed_resources_interval=3D"15secs" --perf_duration=3D"10secs" --p=
erf_interval=3D"1mins" --qos_correction_interval_min=3D"0ns" --quiet=3D"fal=
se" --recover=3D"reconnect" --recovery_timeout=3D"15mins" --registration_ba=
ckoff_factor=3D"10ms" --resources=3D"cpus:2;gpus:0;mem:1024;disk:1024;ports=
:[31000-32000]" --revocable_cpu_low_priority=3D"true" --runtime_dir=3D"/tmp=
/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_oCWVvz" --s=
andbox_directory=3D"/mnt/mesos/sandbox" --strict=3D"true" --switch_user=3D"=
true" --systemd_enable_support=3D"true" --systemd_runtime_directory=3D"/run=
/systemd/system" --version=3D"false" --work_dir=3D"/tmp/ContentType_AgentAP=
ITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F"
I0129 01:47:06.776271 31214 credentials.hpp:86] Loading credential for auth=
entication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession=
WithTTY_0_oCWVvz/credential'
I0129 01:47:06.776535 31214 slave.cpp:352] Agent using credential for: test=
-principal
I0129 01:47:06.776564 31214 credentials.hpp:37] Loading credentials for aut=
hentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessio=
nWithTTY_0_oCWVvz/http_credentials'
I0129 01:47:06.776891 31182 sched.cpp:232] Version: 1.2.0
I0129 01:47:06.776911 31214 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-agent-readonly'
I0129 01:47:06.777385 31214 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-agent-readwrite'
I0129 01:47:06.777791 31201 sched.cpp:336] New master detected at master@17=
2.17.0.2:52605
I0129 01:47:06.777900 31201 sched.cpp:407] Authenticating with master maste=
r@172.17.0.2:52605
I0129 01:47:06.777925 31201 sched.cpp:414] Using default CRAM-MD5 authentic=
atee
I0129 01:47:06.778170 31204 authenticatee.cpp:121] Creating new client SASL=
 connection
I0129 01:47:06.778597 31212 master.cpp:7018] Authenticating scheduler-2a6cf=
d36-0147-4321-b401-67cd97da2acb@172.17.0.2:52605
I0129 01:47:06.778734 31204 authenticator.cpp:414] Starting authentication =
session for crammd5-authenticatee(1145)@172.17.0.2:52605
I0129 01:47:06.779062 31212 authenticator.cpp:98] Creating new server SASL =
connection
I0129 01:47:06.779296 31204 authenticatee.cpp:213] Received SASL authentica=
tion mechanisms: CRAM-MD5
I0129 01:47:06.779405 31204 authenticatee.cpp:239] Attempting to authentica=
te with mechanism 'CRAM-MD5'
I0129 01:47:06.779362 31214 slave.cpp:539] Agent resources: cpus(*):2; mem(=
*):1024; disk(*):1024; ports(*):[31000-32000]
I0129 01:47:06.779520 31214 slave.cpp:547] Agent attributes: [  ]
I0129 01:47:06.779531 31204 authenticator.cpp:204] Received SASL authentica=
tion start
I0129 01:47:06.779657 31214 slave.cpp:552] Agent hostname: 528a1b6e6459
I0129 01:47:06.779726 31204 authenticator.cpp:326] Authentication requires =
more steps
I0129 01:47:06.779829 31204 authenticatee.cpp:259] Received SASL authentica=
tion step
I0129 01:47:06.779862 31212 status_update_manager.cpp:177] Pausing sending =
status updates
I0129 01:47:06.780160 31204 authenticator.cpp:232] Received SASL authentica=
tion step
I0129 01:47:06.780194 31204 auxprop.cpp:109] Request to lookup properties f=
or user: 'test-principal' realm: '528a1b6e6459' server FQDN: '528a1b6e6459'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: false=20
I0129 01:47:06.780218 31204 auxprop.cpp:181] Looking up auxiliary property =
'*userPassword'
I0129 01:47:06.780268 31204 auxprop.cpp:181] Looking up auxiliary property =
'*cmusaslsecretCRAM-MD5'
I0129 01:47:06.780306 31204 auxprop.cpp:109] Request to lookup properties f=
or user: 'test-principal' realm: '528a1b6e6459' server FQDN: '528a1b6e6459'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: true=20
I0129 01:47:06.780328 31204 auxprop.cpp:131] Skipping auxiliary property '*=
userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true
I0129 01:47:06.780344 31204 auxprop.cpp:131] Skipping auxiliary property '*=
cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true
I0129 01:47:06.780365 31204 authenticator.cpp:318] Authentication success
I0129 01:47:06.780539 31201 authenticatee.cpp:299] Authentication success
I0129 01:47:06.780690 31205 master.cpp:7048] Successfully authenticated pri=
ncipal 'test-principal' at scheduler-2a6cfd36-0147-4321-b401-67cd97da2acb@1=
72.17.0.2:52605
I0129 01:47:06.780844 31201 sched.cpp:513] Successfully authenticated with =
master master@172.17.0.2:52605
I0129 01:47:06.780866 31201 sched.cpp:836] Sending SUBSCRIBE call to master=
@172.17.0.2:52605
I0129 01:47:06.780889 31213 authenticator.cpp:432] Authentication session c=
leanup for crammd5-authenticatee(1145)@172.17.0.2:52605
I0129 01:47:06.780980 31201 sched.cpp:869] Will retry registration in 1.756=
982731secs if necessary
I0129 01:47:06.781146 31205 master.cpp:2736] Received SUBSCRIBE call for fr=
amework 'default' at scheduler-2a6cfd36-0147-4321-b401-67cd97da2acb@172.17.=
0.2:52605
I0129 01:47:06.781214 31205 master.cpp:2159] Authorizing framework principa=
l 'test-principal' to receive offers for role '*'
I0129 01:47:06.781610 31201 master.cpp:2812] Subscribing framework default =
with checkpointing disabled and capabilities [  ]
I0129 01:47:06.782166 31201 sched.cpp:759] Framework registered with 525fac=
7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:06.782222 31201 sched.cpp:773] Scheduler::registered took 26355=
ns
I0129 01:47:06.782238 31207 hierarchical.cpp:271] Added framework 525fac7c-=
347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:06.782414 31207 hierarchical.cpp:1675] No allocations performed
I0129 01:47:06.782450 31207 hierarchical.cpp:1770] No inverse offers to sen=
d out!
I0129 01:47:06.782518 31207 hierarchical.cpp:1279] Performed allocation for=
 0 agents in 159756ns
I0129 01:47:06.784039 31210 state.cpp:60] Recovering state from '/tmp/Conte=
ntType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/meta'
I0129 01:47:06.784333 31205 status_update_manager.cpp:203] Recovering statu=
s update manager
I0129 01:47:06.784631 31202 containerizer.cpp:599] Recovering containerizer
I0129 01:47:06.785992 31207 provisioner.cpp:251] Provisioner recovery compl=
ete
I0129 01:47:06.786329 31205 slave.cpp:5422] Finished recovery
I0129 01:47:06.786854 31205 slave.cpp:5596] Querying resource estimator for=
 oversubscribable resources
I0129 01:47:06.787104 31209 status_update_manager.cpp:177] Pausing sending =
status updates
I0129 01:47:06.787161 31201 slave.cpp:929] New master detected at master@17=
2.17.0.2:52605
I0129 01:47:06.787448 31201 slave.cpp:964] Detecting new master
I0129 01:47:06.787632 31201 slave.cpp:5610] Received oversubscribable resou=
rces {} from the resource estimator
I0129 01:47:06.793741 31212 slave.cpp:991] Authenticating with master maste=
r@172.17.0.2:52605
I0129 01:47:06.793848 31212 slave.cpp:1002] Using default CRAM-MD5 authenti=
catee
I0129 01:47:06.794116 31204 authenticatee.cpp:121] Creating new client SASL=
 connection
I0129 01:47:06.794420 31211 master.cpp:7018] Authenticating slave(588)@172.=
17.0.2:52605
I0129 01:47:06.794562 31206 authenticator.cpp:414] Starting authentication =
session for crammd5-authenticatee(1146)@172.17.0.2:52605
I0129 01:47:06.794765 31214 authenticator.cpp:98] Creating new server SASL =
connection
I0129 01:47:06.795016 31206 authenticatee.cpp:213] Received SASL authentica=
tion mechanisms: CRAM-MD5
I0129 01:47:06.795047 31206 authenticatee.cpp:239] Attempting to authentica=
te with mechanism 'CRAM-MD5'
I0129 01:47:06.795155 31214 authenticator.cpp:204] Received SASL authentica=
tion start
I0129 01:47:06.795217 31214 authenticator.cpp:326] Authentication requires =
more steps
I0129 01:47:06.795346 31206 authenticatee.cpp:259] Received SASL authentica=
tion step
I0129 01:47:06.795459 31206 authenticator.cpp:232] Received SASL authentica=
tion step
I0129 01:47:06.795496 31206 auxprop.cpp:109] Request to lookup properties f=
or user: 'test-principal' realm: '528a1b6e6459' server FQDN: '528a1b6e6459'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: false=20
I0129 01:47:06.795519 31206 auxprop.cpp:181] Looking up auxiliary property =
'*userPassword'
I0129 01:47:06.795562 31206 auxprop.cpp:181] Looking up auxiliary property =
'*cmusaslsecretCRAM-MD5'
I0129 01:47:06.795594 31206 auxprop.cpp:109] Request to lookup properties f=
or user: 'test-principal' realm: '528a1b6e6459' server FQDN: '528a1b6e6459'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: true=20
I0129 01:47:06.795611 31206 auxprop.cpp:131] Skipping auxiliary property '*=
userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true
I0129 01:47:06.795624 31206 auxprop.cpp:131] Skipping auxiliary property '*=
cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true
I0129 01:47:06.795645 31206 authenticator.cpp:318] Authentication success
I0129 01:47:06.795729 31214 authenticatee.cpp:299] Authentication success
I0129 01:47:06.795822 31206 master.cpp:7048] Successfully authenticated pri=
ncipal 'test-principal' at slave(588)@172.17.0.2:52605
I0129 01:47:06.795853 31214 authenticator.cpp:432] Authentication session c=
leanup for crammd5-authenticatee(1146)@172.17.0.2:52605
I0129 01:47:06.796175 31206 slave.cpp:1086] Successfully authenticated with=
 master master@172.17.0.2:52605
I0129 01:47:06.796370 31206 slave.cpp:1508] Will retry registration in 13.1=
36239ms if necessary
I0129 01:47:06.796613 31214 master.cpp:5303] Registering agent at slave(588=
)@172.17.0.2:52605 (528a1b6e6459) with id 525fac7c-347d-4045-bbe0-8853f70b0=
864-S0
I0129 01:47:06.797128 31209 registrar.cpp:461] Applied 1 operations in 7288=
0ns; attempting to update the registry
I0129 01:47:06.799134 31209 registrar.cpp:506] Successfully updated the reg=
istry in 1920us
I0129 01:47:06.800021 31203 slave.cpp:4286] Received ping from slave-observ=
er(541)@172.17.0.2:52605
I0129 01:47:06.800220 31206 master.cpp:5377] Registered agent 525fac7c-347d=
-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e6459) wi=
th cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0129 01:47:06.800516 31205 hierarchical.cpp:478] Added agent 525fac7c-347d=
-4045-bbe0-8853f70b0864-S0 (528a1b6e6459) with cpus(*):2; mem(*):1024; disk=
(*):1024; ports(*):[31000-32000] (allocated: {})
I0129 01:47:06.801389 31205 hierarchical.cpp:1770] No inverse offers to sen=
d out!
I0129 01:47:06.801465 31205 hierarchical.cpp:1302] Performed allocation for=
 agent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 in 907380ns
I0129 01:47:06.801585 31203 slave.cpp:1132] Registered with master master@1=
72.17.0.2:52605; given agent ID 525fac7c-347d-4045-bbe0-8853f70b0864-S0
I0129 01:47:06.801614 31203 fetcher.cpp:90] Clearing fetcher cache
I0129 01:47:06.801818 31214 status_update_manager.cpp:184] Resuming sending=
 status updates
I0129 01:47:06.801955 31201 master.cpp:6847] Sending 1 offers to framework =
525fac7c-347d-4045-bbe0-8853f70b0864-0000 (default) at scheduler-2a6cfd36-0=
147-4321-b401-67cd97da2acb@172.17.0.2:52605
I0129 01:47:06.802053 31203 slave.cpp:1160] Checkpointing SlaveInfo to '/tm=
p/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/met=
a/slaves/525fac7c-347d-4045-bbe0-8853f70b0864-S0/slave.info'
I0129 01:47:06.802398 31203 slave.cpp:1198] Forwarding total oversubscribed=
 resources {}
I0129 01:47:06.802531 31201 sched.cpp:933] Scheduler::resourceOffers took 1=
38849ns
I0129 01:47:06.802541 31203 master.cpp:5876] Received update of agent 525fa=
c7c-347d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e=
6459) with total oversubscribed resources {}
I0129 01:47:06.803011 31212 hierarchical.cpp:548] Agent 525fac7c-347d-4045-=
bbe0-8853f70b0864-S0 (528a1b6e6459) updated with oversubscribed resources {=
} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], all=
ocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0129 01:47:06.803258 31212 hierarchical.cpp:1675] No allocations performed
I0129 01:47:06.803334 31212 hierarchical.cpp:1770] No inverse offers to sen=
d out!
I0129 01:47:06.803416 31212 hierarchical.cpp:1302] Performed allocation for=
 agent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 in 306702ns
I0129 01:47:06.804782 31201 master.cpp:3730] Processing ACCEPT call for off=
ers: [ 525fac7c-347d-4045-bbe0-8853f70b0864-O0 ] on agent 525fac7c-347d-404=
5-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e6459) for fr=
amework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 (default) at scheduler-2a=
6cfd36-0147-4321-b401-67cd97da2acb@172.17.0.2:52605
I0129 01:47:06.804949 31201 master.cpp:3318] Authorizing framework principa=
l 'test-principal' to launch task b7415522-fc0f-44d8-b4cb-f4105d7c9458
I0129 01:47:06.808382 31201 master.cpp:8900] Adding task b7415522-fc0f-44d8=
-b4cb-f4105d7c9458 with resources cpus(*):2; mem(*):1024; disk(*):1024; por=
ts(*):[31000-32000] on agent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 at sla=
ve(588)@172.17.0.2:52605 (528a1b6e6459)
I0129 01:47:06.808854 31201 master.cpp:4380] Launching task b7415522-fc0f-4=
4d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-000=
0 (default) at scheduler-2a6cfd36-0147-4321-b401-67cd97da2acb@172.17.0.2:52=
605 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32=
000] on agent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.=
0.2:52605 (528a1b6e6459)
I0129 01:47:06.809702 31209 slave.cpp:1576] Got assigned task 'b7415522-fc0=
f-44d8-b4cb-f4105d7c9458' for framework 525fac7c-347d-4045-bbe0-8853f70b086=
4-0000
I0129 01:47:06.810569 31209 slave.cpp:1736] Launching task 'b7415522-fc0f-4=
4d8-b4cb-f4105d7c9458' for framework 525fac7c-347d-4045-bbe0-8853f70b0864-0=
000
I0129 01:47:06.811465 31209 paths.cpp:547] Trying to chown '/tmp/ContentTyp=
e_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525fac7c=
-347d-4045-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853f70b=
0864-0000/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e304f6c-2f13=
-48a2-9e31-fb31e77836ae' to user 'mesos'
I0129 01:47:06.811852 31209 slave.cpp:6331] Launching executor 'b7415522-fc=
0f-44d8-b4cb-f4105d7c9458' of framework 525fac7c-347d-4045-bbe0-8853f70b086=
4-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/Conten=
tType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525f=
ac7c-347d-4045-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853=
f70b0864-0000/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e304f6c-=
2f13-48a2-9e31-fb31e77836ae'
I0129 01:47:06.812742 31208 containerizer.cpp:992] Starting container 0e304=
f6c-2f13-48a2-9e31-fb31e77836ae for executor 'b7415522-fc0f-44d8-b4cb-f4105=
d7c9458' of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:06.812901 31209 slave.cpp:2058] Queued task 'b7415522-fc0f-44d8=
-b4cb-f4105d7c9458' for executor 'b7415522-fc0f-44d8-b4cb-f4105d7c9458' of =
framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:06.812989 31209 slave.cpp:882] Successfully attached file '/tmp=
/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slav=
es/525fac7c-347d-4045-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bb=
e0-8853f70b0864-0000/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e=
304f6c-2f13-48a2-9e31-fb31e77836ae'
I0129 01:47:06.817917 31215 containerizer.cpp:1541] Launching 'mesos-contai=
nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"argumen=
ts":["mesos-executor","--launcher_dir=3D\/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.2:52605"},{"name":"MESOS_CHECKPOI=
NT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_Agen=
tAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F\/slaves\/525fac7c-347=
d-4045-bbe0-8853f70b0864-S0\/frameworks\/525fac7c-347d-4045-bbe0-8853f70b08=
64-0000\/executors\/b7415522-fc0f-44d8-b4cb-f4105d7c9458\/runs\/0e304f6c-2f=
13-48a2-9e31-fb31e77836ae"},{"name":"MESOS_EXECUTOR_ID","value":"b7415522-f=
c0f-44d8-b4cb-f4105d7c9458"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD"=
,"value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"525fac7c-347d-4045-=
bbe0-8853f70b0864-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"}=
,{"name":"MESOS_SLAVE_ID","value":"525fac7c-347d-4045-bbe0-8853f70b0864-S0"=
},{"name":"MESOS_SLAVE_PID","value":"slave(588)@172.17.0.2:52605"},{"name":=
"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContai=
nerSessionWithTTY_0_6Bxd9F\/slaves\/525fac7c-347d-4045-bbe0-8853f70b0864-S0=
\/frameworks\/525fac7c-347d-4045-bbe0-8853f70b0864-0000\/executors\/b741552=
2-fc0f-44d8-b4cb-f4105d7c9458\/runs\/0e304f6c-2f13-48a2-9e31-fb31e77836ae"}=
]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSess=
ionWithTTY_0_6Bxd9F\/slaves\/525fac7c-347d-4045-bbe0-8853f70b0864-S0\/frame=
works\/525fac7c-347d-4045-bbe0-8853f70b0864-0000\/executors\/b7415522-fc0f-=
44d8-b4cb-f4105d7c9458\/runs\/0e304f6c-2f13-48a2-9e31-fb31e77836ae\/stderr"=
,"type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNested=
ContainerSessionWithTTY_0_6Bxd9F\/slaves\/525fac7c-347d-4045-bbe0-8853f70b0=
864-S0\/frameworks\/525fac7c-347d-4045-bbe0-8853f70b0864-0000\/executors\/b=
7415522-fc0f-44d8-b4cb-f4105d7c9458\/runs\/0e304f6c-2f13-48a2-9e31-fb31e778=
36ae\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/Con=
tentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F\/slaves\=
/525fac7c-347d-4045-bbe0-8853f70b0864-S0\/frameworks\/525fac7c-347d-4045-bb=
e0-8853f70b0864-0000\/executors\/b7415522-fc0f-44d8-b4cb-f4105d7c9458\/runs=
\/0e304f6c-2f13-48a2-9e31-fb31e77836ae"}" --pipe_read=3D"15" --pipe_write=
=3D"17" --runtime_directory=3D"/tmp/ContentType_AgentAPITest_LaunchNestedCo=
ntainerSessionWithTTY_0_oCWVvz/containers/0e304f6c-2f13-48a2-9e31-fb31e7783=
6ae" --unshare_namespace_mnt=3D"false"'
I0129 01:47:06.823396 31215 launcher.cpp:135] Forked child with pid '10932'=
 for container '0e304f6c-2f13-48a2-9e31-fb31e77836ae'
I0129 01:47:06.828348 31211 fetcher.cpp:349] Starting to fetch URIs for con=
tainer: 0e304f6c-2f13-48a2-9e31-fb31e77836ae, directory: /tmp/ContentType_A=
gentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525fac7c-34=
7d-4045-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853f70b086=
4-0000/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e304f6c-2f13-48=
a2-9e31-fb31e77836ae
I0129 01:47:07.005422 31206 slave.cpp:3325] Got registration for executor '=
b7415522-fc0f-44d8-b4cb-f4105d7c9458' of framework 525fac7c-347d-4045-bbe0-=
8853f70b0864-0000 from executor(1)@172.17.0.2:43889
I0129 01:47:07.008360 31216 slave.cpp:2271] Sending queued task 'b7415522-f=
c0f-44d8-b4cb-f4105d7c9458' to executor 'b7415522-fc0f-44d8-b4cb-f4105d7c94=
58' of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 at executor(1)@1=
72.17.0.2:43889
I0129 01:47:07.024600 31210 slave.cpp:3756] Handling status update TASK_RUN=
NING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b7415522-fc0f-44=
d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000=
 from executor(1)@172.17.0.2:43889
I0129 01:47:07.027077 31203 status_update_manager.cpp:323] Received status =
update TASK_RUNNING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b=
7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-88=
53f70b0864-0000
I0129 01:47:07.027180 31203 status_update_manager.cpp:500] Creating StatusU=
pdate stream for task b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525=
fac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.027844 31203 status_update_manager.cpp:377] Forwarding updat=
e TASK_RUNNING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b74155=
22-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70=
b0864-0000 to the agent
I0129 01:47:07.028298 31213 slave.cpp:4196] Forwarding the update TASK_RUNN=
ING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b7415522-fc0f-44d=
8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 =
to master@172.17.0.2:52605
I0129 01:47:07.028584 31213 slave.cpp:4090] Status update manager successfu=
lly handled status update TASK_RUNNING (UUID: 63796167-fc5a-4bd4-8f16-41e24=
e015823) for task b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7=
c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.028638 31213 slave.cpp:4106] Sending acknowledgement for sta=
tus update TASK_RUNNING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for ta=
sk b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe=
0-8853f70b0864-0000 to executor(1)@172.17.0.2:43889
I0129 01:47:07.028801 31207 master.cpp:6021] Status update TASK_RUNNING (UU=
ID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b7415522-fc0f-44d8-b4cb-=
f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 from ag=
ent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 =
(528a1b6e6459)
I0129 01:47:07.028880 31207 master.cpp:6089] Forwarding status update TASK_=
RUNNING (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for task b7415522-fc0f=
-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0=
000
I0129 01:47:07.029150 31207 master.cpp:8155] Updating the state of task b74=
15522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853=
f70b0864-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNIN=
G)
I0129 01:47:07.029597 31214 sched.cpp:1041] Scheduler::statusUpdate took 19=
0198ns
I0129 01:47:07.030319 31212 master.cpp:5017] Processing ACKNOWLEDGE call 63=
796167-fc5a-4bd4-8f16-41e24e015823 for task b7415522-fc0f-44d8-b4cb-f4105d7=
c9458 of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 (default) at s=
cheduler-2a6cfd36-0147-4321-b401-67cd97da2acb@172.17.0.2:52605 on agent 525=
fac7c-347d-4045-bbe0-8853f70b0864-S0
I0129 01:47:07.030860 31204 status_update_manager.cpp:395] Received status =
update acknowledgement (UUID: 63796167-fc5a-4bd4-8f16-41e24e015823) for tas=
k b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0=
-8853f70b0864-0000
I0129 01:47:07.031313 31201 slave.cpp:3045] Status update manager successfu=
lly handled status update acknowledgement (UUID: 63796167-fc5a-4bd4-8f16-41=
e24e015823) for task b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525f=
ac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.034726 31204 process.cpp:3697] Handling HTTP event for proce=
ss 'slave(588)' with path: '/slave(588)/api/v1'
I0129 01:47:07.036304 31202 http.cpp:303] HTTP POST for /slave(588)/api/v1 =
from 172.17.0.2:58766
I0129 01:47:07.036962 31202 http.cpp:464] Processing call LAUNCH_NESTED_CON=
TAINER_SESSION
I0129 01:47:07.037560 31210 containerizer.cpp:1808] Starting nested contain=
er 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf88257=
7
I0129 01:47:07.038013 31210 containerizer.cpp:1832] Trying to chown '/tmp/C=
ontentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves=
/525fac7c-347d-4045-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0=
-8853f70b0864-0000/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e30=
4f6c-2f13-48a2-9e31-fb31e77836ae/containers/1d1fcfd9-5aaf-493b-b237-259eaf8=
82577' to user 'mesos'
I0129 01:47:07.041610 31202 switchboard.cpp:434] Allocated pseudo terminal =
'/dev/pts/1' for container 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5a=
af-493b-b237-259eaf882577
I0129 01:47:07.042568 31202 switchboard.cpp:571] Launching 'mesos-io-switch=
board' with flags '--heartbeat_interval=3D"30secs" --help=3D"false" --socke=
t_address=3D"/tmp/mesos-io-switchboard-1e11f86a-125b-4769-97bc-3b2cd0733dcc=
" --stderr_from_fd=3D"21" --stderr_to_fd=3D"2" --stdin_to_fd=3D"21" --stdou=
t_from_fd=3D"21" --stdout_to_fd=3D"1" --tty=3D"true" --wait_for_connection=
=3D"true"' for container 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf=
-493b-b237-259eaf882577
I0129 01:47:07.049176 31202 switchboard.cpp:601] Created I/O switchboard se=
rver (pid: 10991) listening on socket file '/tmp/mesos-io-switchboard-1e11f=
86a-125b-4769-97bc-3b2cd0733dcc' for container 0e304f6c-2f13-48a2-9e31-fb31=
e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577
I0129 01:47:07.051885 31205 containerizer.cpp:1541] Launching 'mesos-contai=
nerizer' with flags '--help=3D"false" --launch_info=3D"{"command":{"shell":=
true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{=
"fd":22,"type":"FD"},"in":{"fd":22,"type":"FD"},"out":{"fd":22,"type":"FD"}=
,"tty_slave_path":"\/dev\/pts\/1","user":"mesos"}" --pipe_read=3D"21" --pip=
e_write=3D"42" --runtime_directory=3D"/tmp/ContentType_AgentAPITest_LaunchN=
estedContainerSessionWithTTY_0_oCWVvz/containers/0e304f6c-2f13-48a2-9e31-fb=
31e77836ae/containers/1d1fcfd9-5aaf-493b-b237-259eaf882577" --unshare_names=
pace_mnt=3D"false"'
I0129 01:47:07.057840 31205 launcher.cpp:135] Forked child with pid '10995'=
 for container '0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b23=
7-259eaf882577'
I0129 01:47:07.062804 31209 fetcher.cpp:349] Starting to fetch URIs for con=
tainer: 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf=
882577, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessi=
onWithTTY_0_6Bxd9F/slaves/525fac7c-347d-4045-bbe0-8853f70b0864-S0/framework=
s/525fac7c-347d-4045-bbe0-8853f70b0864-0000/executors/b7415522-fc0f-44d8-b4=
cb-f4105d7c9458/runs/0e304f6c-2f13-48a2-9e31-fb31e77836ae/containers/1d1fcf=
d9-5aaf-493b-b237-259eaf882577

**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(609)@172.17.0.2:52605 that it is cur=
rently executing.
I0129 01:47:07.180491 31215 http.cpp:2647] Received EOF attach response for=
 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577
I0129 01:47:07.181474 31211 containerizer.cpp:2119] Destroying container 0e=
304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577 in =
RUNNING state
I0129 01:47:07.182045 31211 launcher.cpp:151] Asked to destroy container 0e=
304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577
W0129 01:47:07.183346 31215 http.cpp:2662] Launch nested container session =
connection for container 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf=
-493b-b237-259eaf882577 closed
I0129 01:47:07.187363 31182 sched.cpp:2021] Asked to stop the driver
I0129 01:47:07.187564 31214 sched.cpp:1203] Stopping framework 525fac7c-347=
d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.188474 31211 master.cpp:7560] Processing TEARDOWN call for f=
ramework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 (default) at scheduler-2=
a6cfd36-0147-4321-b401-67cd97da2acb@172.17.0.2:52605
I0129 01:47:07.188658 31211 master.cpp:7572] Removing framework 525fac7c-34=
7d-4045-bbe0-8853f70b0864-0000 (default) at scheduler-2a6cfd36-0147-4321-b4=
01-67cd97da2acb@172.17.0.2:52605
I0129 01:47:07.188823 31211 master.cpp:3080] Deactivating framework 525fac7=
c-347d-4045-bbe0-8853f70b0864-0000 (default) at scheduler-2a6cfd36-0147-432=
1-b401-67cd97da2acb@172.17.0.2:52605
I0129 01:47:07.189213 31207 hierarchical.cpp:386] Deactivated framework 525=
fac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.189306 31203 slave.cpp:2598] Asked to shut down framework 52=
5fac7c-347d-4045-bbe0-8853f70b0864-0000 by master@172.17.0.2:52605
I0129 01:47:07.189357 31203 slave.cpp:2623] Shutting down framework 525fac7=
c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.189411 31203 slave.cpp:5005] Shutting down executor 'b741552=
2-fc0f-44d8-b4cb-f4105d7c9458' of framework 525fac7c-347d-4045-bbe0-8853f70=
b0864-0000 at executor(1)@172.17.0.2:43889
I0129 01:47:07.189401 31211 master.cpp:8155] Updating the state of task b74=
15522-fc0f-44d8-b4cb-f4105d7c9458 of framework 525fac7c-347d-4045-bbe0-8853=
f70b0864-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0129 01:47:07.190676 31204 containerizer.cpp:2119] Destroying container 0e=
304f6c-2f13-48a2-9e31-fb31e77836ae in RUNNING state
I0129 01:47:07.190798 31207 hierarchical.cpp:1011] Recovered cpus(*):2; mem=
(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):10=
24; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 525fac7c-=
347d-4045-bbe0-8853f70b0864-S0 from framework 525fac7c-347d-4045-bbe0-8853f=
70b0864-0000
I0129 01:47:07.191231 31211 master.cpp:8249] Removing task b7415522-fc0f-44=
d8-b4cb-f4105d7c9458 with resources cpus(*):2; mem(*):1024; disk(*):1024; p=
orts(*):[31000-32000] of framework 525fac7c-347d-4045-bbe0-8853f70b0864-000=
0 on agent 525fac7c-347d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2=
:52605 (528a1b6e6459)
I0129 01:47:07.192201 31211 hierarchical.cpp:337] Removed framework 525fac7=
c-347d-4045-bbe0-8853f70b0864-0000
E0129 01:47:07.234925 31201 switchboard.cpp:889] Unexpected termination of =
I/O switchboard server: 'IOSwitchboard' exited with status 1 for container =
0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577
I0129 01:47:07.235450 31201 containerizer.cpp:2482] Container 0e304f6c-2f13=
-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b237-259eaf882577 has exited
I0129 01:47:07.237144 31205 provisioner.cpp:322] Ignoring destroy request f=
or unknown container 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493=
b-b237-259eaf882577
I0129 01:47:07.237336 31210 containerizer.cpp:2398] Checkpointing terminati=
on state to nested container's runtime directory '/tmp/ContentType_AgentAPI=
Test_LaunchNestedContainerSessionWithTTY_0_oCWVvz/containers/0e304f6c-2f13-=
48a2-9e31-fb31e77836ae/containers/1d1fcfd9-5aaf-493b-b237-259eaf882577/term=
ination'
I0129 01:47:07.238113 31210 launcher.cpp:151] Asked to destroy container 0e=
304f6c-2f13-48a2-9e31-fb31e77836ae
I0129 01:47:07.249295 31216 slave.cpp:4328] Got exited event for executor(1=
)@172.17.0.2:43889
I0129 01:47:07.337250 31202 containerizer.cpp:2482] Container 0e304f6c-2f13=
-48a2-9e31-fb31e77836ae has exited
I0129 01:47:07.340733 31204 provisioner.cpp:322] Ignoring destroy request f=
or unknown container 0e304f6c-2f13-48a2-9e31-fb31e77836ae
I0129 01:47:07.342382 31204 slave.cpp:4691] Executor 'b7415522-fc0f-44d8-b4=
cb-f4105d7c9458' of framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000 ter=
minated with signal Killed
I0129 01:47:07.342463 31204 slave.cpp:4791] Cleaning up executor 'b7415522-=
fc0f-44d8-b4cb-f4105d7c9458' of framework 525fac7c-347d-4045-bbe0-8853f70b0=
864-0000 at executor(1)@172.17.0.2:43889
I0129 01:47:07.342941 31204 slave.cpp:4879] Cleaning up framework 525fac7c-=
347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.343423 31204 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAP=
ITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525fac7c-347d-404=
5-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853f70b0864-0000=
/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458/runs/0e304f6c-2f13-48a2-9e3=
1-fb31e77836ae' for gc 6.99999603365926days in the future
I0129 01:47:07.343621 31204 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAP=
ITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525fac7c-347d-404=
5-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853f70b0864-0000=
/executors/b7415522-fc0f-44d8-b4cb-f4105d7c9458' for gc 6.99999603146074day=
s in the future
I0129 01:47:07.343751 31204 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAP=
ITest_LaunchNestedContainerSessionWithTTY_0_6Bxd9F/slaves/525fac7c-347d-404=
5-bbe0-8853f70b0864-S0/frameworks/525fac7c-347d-4045-bbe0-8853f70b0864-0000=
' for gc 6.9999960292237days in the future
I0129 01:47:07.343873 31204 status_update_manager.cpp:285] Closing status u=
pdate streams for framework 525fac7c-347d-4045-bbe0-8853f70b0864-0000
I0129 01:47:07.343926 31204 status_update_manager.cpp:531] Cleaning up stat=
us update stream for task b7415522-fc0f-44d8-b4cb-f4105d7c9458 of framework=
 525fac7c-347d-4045-bbe0-8853f70b0864-0000
W0129 01:47:07.345073 31206 containerizer.cpp:2107] Attempted to destroy un=
known container 0e304f6c-2f13-48a2-9e31-fb31e77836ae.1d1fcfd9-5aaf-493b-b23=
7-259eaf882577
I0129 01:47:07.346194 31182 slave.cpp:801] Agent terminating
I0129 01:47:07.346673 31212 master.cpp:1263] Agent 525fac7c-347d-4045-bbe0-=
8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e6459) disconnected
I0129 01:47:07.347143 31212 master.cpp:3117] Disconnecting agent 525fac7c-3=
47d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e6459)
I0129 01:47:07.347982 31212 master.cpp:3136] Deactivating agent 525fac7c-34=
7d-4045-bbe0-8853f70b0864-S0 at slave(588)@172.17.0.2:52605 (528a1b6e6459)
I0129 01:47:07.348242 31216 hierarchical.cpp:577] Agent 525fac7c-347d-4045-=
bbe0-8853f70b0864-S0 deactivated
I0129 01:47:07.353482 31182 master.cpp:1102] Master terminating
I0129 01:47:07.354373 31208 hierarchical.cpp:510] Removed agent 525fac7c-34=
7d-4045-bbe0-8853f70b0864-S0
[       OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0=
 (606 ms)
[ RUN      ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1
I0129 01:47:07.362337 31182 cluster.cpp:160] Creating default 'local' autho=
rizer
I0129 01:47:07.365028 31212 master.cpp:383] Master 15845ec6-f7a4-4449-9c90-=
2314a16d7199 (528a1b6e6459) started on 172.17.0.2:52605
I0129 01:47:07.365053 31212 master.cpp:385] Flags at startup: --acls=3D"" -=
-agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo=
cation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authenticate_ag=
ents=3D"true" --authenticate_frameworks=3D"true" --authenticate_http_framew=
orks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_rea=
dwrite=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local" --cred=
entials=3D"/tmp/nrZwOH/credentials" --framework_sorter=3D"drf" --help=3D"fa=
lse" --hostname_lookup=3D"true" --http_authenticators=3D"basic" --http_fram=
ework_authenticators=3D"basic" --initialize_driver_logging=3D"true" --log_a=
uto_initialize=3D"true" --logbufsecs=3D"0" --logging_level=3D"INFO" --max_a=
gent_ping_timeouts=3D"5" --max_completed_frameworks=3D"50" --max_completed_=
tasks_per_framework=3D"1000" --max_unreachable_tasks_per_framework=3D"1000"=
 --quiet=3D"false" --recovery_agent_removal_limit=3D"100%" --registry=3D"in=
_memory" --registry_fetch_timeout=3D"1mins" --registry_gc_interval=3D"15min=
s" --registry_max_agent_age=3D"2weeks" --registry_max_agent_count=3D"102400=
" --registry_store_timeout=3D"100secs" --registry_strict=3D"false" --root_s=
ubmissions=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_dir=
=3D"/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir=3D"/tmp/nrZwOH/m=
aster" --zk_session_timeout=3D"10secs"
I0129 01:47:07.365435 31212 master.cpp:435] Master only allowing authentica=
ted frameworks to register
I0129 01:47:07.365449 31212 master.cpp:449] Master only allowing authentica=
ted agents to register
I0129 01:47:07.365455 31212 master.cpp:462] Master only allowing authentica=
ted HTTP frameworks to register
I0129 01:47:07.365463 31212 credentials.hpp:37] Loading credentials for aut=
hentication from '/tmp/nrZwOH/credentials'
I0129 01:47:07.365718 31212 master.cpp:507] Using default 'crammd5' authent=
icator
I0129 01:47:07.365849 31212 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-readonly'
I0129 01:47:07.365962 31212 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-readwrite'
I0129 01:47:07.366044 31212 http.cpp:922] Using default 'basic' HTTP authen=
ticator for realm 'mesos-master-scheduler'
I0129 01:47:07.366122 31212 master.cpp:587] Authorization enabled
I0129 01:47:07.367403 31215 hierarchical.cpp:151] Initialized hierarchical =
allocator process
I0129 01:47:07.367498 31210 whitelist_watcher.cpp:77] No whitelist given
I0129 01:47:07.369630 31208 master.cpp:2123] Elected as the leading master!
I0129 01:47:07.369664 31208 master.cpp:1645] Recovering from registrar
I0129 01:47:07.369947 31208 registrar.cpp:329] Recovering registrar
I0129 01:47:07.371074 31208 registrar.cpp:362] Successfully fetched the reg=
istry (0B) in 1.083648ms
I0129 01:47:07.371201 31208 registrar.cpp:461] Applied 1 operations in 2564=
8ns; attempting to update the registry
I0129 01:47:07.372354 31215 registrar.cpp:506] Successfully updated the reg=
istry in 1.056768ms
I0129 01:47:07.372536 31215 registrar.cpp:392] Successfully recovered regis=
trar
I0129 01:47:07.373064 31204 master.cpp:1761] Recovered 0 agents from the re=
gistry (129B); allowing 10mins for agents to re-register
I0129 01:47:07.373103 31213 hierarchical.cpp:178] Skipping recovery of hier=
archical allocator: nothing to recover
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1485652063-29707
Error response from daemon: conflict: unable to remove repository reference=
 "mesos-1485652063-29707" (must force) - container 528a1b6e6459 is using it=
s referenced image 26aae01ba559