See
<https://builds.apache.org/job/Mesos/COMPILER=gcc,LABEL=docker%7C%7CHadoop,OS=ubuntu%3A14.10/381/changes>
Changes:
[toenshoff] Fix a comment in Slave.
------------------------------------------
[...truncated 33963 lines...]
I0617 11:31:09.577188 26189 recover.cpp:195] Received a recover response from a
replica in STARTING status
I0617 11:31:09.577595 26192 recover.cpp:566] Updating replica status to VOTING
I0617 11:31:09.633895 26186 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 56.11347ms
I0617 11:31:09.633994 26186 replica.cpp:323] Persisted replica status to VOTING
I0617 11:31:09.634260 26193 recover.cpp:580] Successfully joined the Paxos group
I0617 11:31:09.634635 26193 recover.cpp:464] Recover process terminated
I0617 11:31:09.634893 26181 log.cpp:661] Attempting to start the writer
I0617 11:31:09.636265 26190 replica.cpp:477] Replica received implicit promise
request with proposal 1
I0617 11:31:09.676028 26190 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 39.725253ms
I0617 11:31:09.676084 26190 replica.cpp:345] Persisted promised to 1
I0617 11:31:09.676869 26183 coordinator.cpp:230] Coordinator attemping to fill
missing position
I0617 11:31:09.678342 26190 replica.cpp:378] Replica received explicit promise
request for position 0 with proposal 2
I0617 11:31:09.724710 26190 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 46.312379ms
I0617 11:31:09.724771 26190 replica.cpp:679] Persisted action at 0
I0617 11:31:09.726059 26181 replica.cpp:511] Replica received write request for
position 0
I0617 11:31:09.726142 26181 leveldb.cpp:438] Reading position from leveldb took
45048ns
I0617 11:31:09.766345 26181 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 40.147938ms
I0617 11:31:09.766398 26181 replica.cpp:679] Persisted action at 0
I0617 11:31:09.767053 26181 replica.cpp:658] Replica received learned notice
for position 0
I0617 11:31:09.800096 26181 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 33.004498ms
I0617 11:31:09.800154 26181 replica.cpp:679] Persisted action at 0
I0617 11:31:09.800182 26181 replica.cpp:664] Replica learned NOP action at
position 0
I0617 11:31:09.801007 26183 log.cpp:677] Writer started with ending position 0
I0617 11:31:09.802140 26178 leveldb.cpp:438] Reading position from leveldb took
44937ns
I0617 11:31:09.804577 26182 registrar.cpp:346] Successfully fetched the
registry (0B) in 281.55904ms
I0617 11:31:09.804694 26182 registrar.cpp:445] Applied 1 operations in 31037ns;
attempting to update the 'registry'
I0617 11:31:09.806910 26193 log.cpp:685] Attempting to append 131 bytes to the
log
I0617 11:31:09.807075 26191 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I0617 11:31:09.808097 26182 replica.cpp:511] Replica received write request for
position 1
I0617 11:31:09.830376 26182 leveldb.cpp:343] Persisting action (150 bytes) to
leveldb took 22.226836ms
I0617 11:31:09.830433 26182 replica.cpp:679] Persisted action at 1
I0617 11:31:09.831404 26183 replica.cpp:658] Replica received learned notice
for position 1
I0617 11:31:09.860707 26183 leveldb.cpp:343] Persisting action (152 bytes) to
leveldb took 29.263052ms
I0617 11:31:09.860764 26183 replica.cpp:679] Persisted action at 1
I0617 11:31:09.860795 26183 replica.cpp:664] Replica learned APPEND action at
position 1
I0617 11:31:09.862066 26178 registrar.cpp:490] Successfully updated the
'registry' in 57.302016ms
I0617 11:31:09.862227 26178 registrar.cpp:376] Successfully recovered registrar
I0617 11:31:09.862321 26182 log.cpp:704] Attempting to truncate the log to 1
I0617 11:31:09.862517 26191 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 2
I0617 11:31:09.862572 26192 master.cpp:1284] Recovered 0 slaves from the
Registry (95B) ; allowing 10mins for slaves to re-register
I0617 11:31:09.863587 26187 replica.cpp:511] Replica received write request for
position 2
I0617 11:31:09.967244 26187 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 103.618525ms
I0617 11:31:09.967293 26187 replica.cpp:679] Persisted action at 2
I0617 11:31:09.968144 26187 replica.cpp:658] Replica received learned notice
for position 2
I0617 11:31:10.120379 26187 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 152.199216ms
I0617 11:31:10.120498 26187 leveldb.cpp:401] Deleting ~1 keys from leveldb took
56837ns
I0617 11:31:10.120534 26187 replica.cpp:679] Persisted action at 2
I0617 11:31:10.120569 26187 replica.cpp:664] Replica learned TRUNCATE action at
position 2
I0617 11:31:10.128904 26159 containerizer.cpp:111] Using isolation:
posix/cpu,posix/mem
I0617 11:31:10.133965 26193 slave.cpp:188] Slave started on
43)@172.17.0.153:54856
I0617 11:31:10.133993 26193 slave.cpp:189] Flags at startup:
--authenticatee="crammd5" --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/FetcherCacheTest_SimpleEviction_avz3Qq/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker"
--docker_kill_orphans="true" --docker_remove_delay="6hrs"
--docker_sandbox_directory="/mnt/mesos/sandbox"
--docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/FetcherCacheTest_SimpleEviction_avz3Qq/fetch"
--fetcher_cache_size="90B" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher_dir="/mesos/mesos-0.23.0/_build/src" --logbufsecs="0"
--logging_level="INFO" --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"
--resource_monitoring_interval="1secs" --resources="cpus(*):1000; mem(*):1000"
--revocable_cpu_low_priority="true" --strict="true" --switch_user="true"
--version="false" --work_dir="/tmp/FetcherCacheTest_SimpleEviction_avz3Qq"
I0617 11:31:10.134497 26193 credentials.hpp:85] Loading credential for
authentication from '/tmp/FetcherCacheTest_SimpleEviction_avz3Qq/credential'
I0617 11:31:10.134763 26193 slave.cpp:319] Slave using credential for:
test-principal
I0617 11:31:10.135195 26193 slave.cpp:353] Slave resources: cpus(*):1000;
mem(*):1000; disk(*):3.70122e+06; ports(*):[31000-32000]
I0617 11:31:10.135292 26193 slave.cpp:383] Slave hostname: 4ae164286465
I0617 11:31:10.135310 26193 slave.cpp:388] Slave checkpoint: true
I0617 11:31:10.136224 26178 state.cpp:35] Recovering state from
'/tmp/FetcherCacheTest_SimpleEviction_avz3Qq/meta'
I0617 11:31:10.136596 26183 status_update_manager.cpp:201] Recovering status
update manager
I0617 11:31:10.136776 26189 containerizer.cpp:312] Recovering containerizer
I0617 11:31:10.137560 26187 slave.cpp:3919] Finished recovery
I0617 11:31:10.138013 26187 slave.cpp:4072] Querying resource estimator for
oversubscribable resources
I0617 11:31:10.138214 26178 status_update_manager.cpp:175] Pausing sending
status updates
I0617 11:31:10.138212 26187 slave.cpp:679] New master detected at
master@172.17.0.153:54856
I0617 11:31:10.138300 26187 slave.cpp:742] Authenticating with master
master@172.17.0.153:54856
I0617 11:31:10.138321 26187 slave.cpp:747] Using default CRAM-MD5 authenticatee
I0617 11:31:10.138433 26187 slave.cpp:715] Detecting new master
I0617 11:31:10.138473 26189 authenticatee.hpp:139] Creating new client SASL
connection
I0617 11:31:10.138550 26187 slave.cpp:4086] Received oversubscribable resources
from the resource estimator
I0617 11:31:10.138737 26186 master.cpp:4187] Authenticating
slave(43)@172.17.0.153:54856
I0617 11:31:10.138839 26192 authenticator.cpp:406] Starting authentication
session for crammd5_authenticatee(133)@172.17.0.153:54856
I0617 11:31:10.139055 26193 authenticator.cpp:92] Creating new server SASL
connection
I0617 11:31:10.139286 26179 authenticatee.hpp:230] Received SASL authentication
mechanisms: CRAM-MD5
I0617 11:31:10.139317 26179 authenticatee.hpp:256] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0617 11:31:10.139435 26190 authenticator.cpp:197] Received SASL authentication
start
I0617 11:31:10.139487 26190 authenticator.cpp:319] Authentication requires more
steps
I0617 11:31:10.139628 26178 authenticatee.hpp:276] Received SASL authentication
step
I0617 11:31:10.139753 26190 authenticator.cpp:225] Received SASL authentication
step
I0617 11:31:10.139785 26190 auxprop.cpp:102] Request to lookup properties for
user: 'test-principal' realm: '4ae164286465' server FQDN: '4ae164286465'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0617 11:31:10.139796 26190 auxprop.cpp:174] Looking up auxiliary property
'*userPassword'
I0617 11:31:10.139848 26190 auxprop.cpp:174] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0617 11:31:10.139884 26190 auxprop.cpp:102] Request to lookup properties for
user: 'test-principal' realm: '4ae164286465' server FQDN: '4ae164286465'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0617 11:31:10.139894 26190 auxprop.cpp:124] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0617 11:31:10.139900 26190 auxprop.cpp:124] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0617 11:31:10.139919 26190 authenticator.cpp:311] Authentication success
I0617 11:31:10.139998 26179 authenticatee.hpp:316] Authentication success
I0617 11:31:10.140036 26184 master.cpp:4217] Successfully authenticated
principal 'test-principal' at slave(43)@172.17.0.153:54856
I0617 11:31:10.140084 26193 authenticator.cpp:424] Authentication session
cleanup for crammd5_authenticatee(133)@172.17.0.153:54856
I0617 11:31:10.140264 26179 slave.cpp:813] Successfully authenticated with
master master@172.17.0.153:54856
I0617 11:31:10.140380 26179 slave.cpp:1172] Will retry registration in
16.33064ms if necessary
I0617 11:31:10.140591 26184 master.cpp:3166] Registering slave at
slave(43)@172.17.0.153:54856 (4ae164286465) with id
20150617-113109-2566918572-54856-26159-S0
I0617 11:31:10.141015 26180 registrar.cpp:445] Applied 1 operations in 59384ns;
attempting to update the 'registry'
I0617 11:31:10.143353 26187 log.cpp:685] Attempting to append 305 bytes to the
log
I0617 11:31:10.143507 26190 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 3
I0617 11:31:10.144217 26193 replica.cpp:511] Replica received write request for
position 3
I0617 11:31:10.158030 26179 slave.cpp:1172] Will retry registration in
32.793213ms if necessary
I0617 11:31:10.158164 26187 master.cpp:3154] Ignoring register slave message
from slave(43)@172.17.0.153:54856 (4ae164286465) as admission is already in
progress
I0617 11:31:10.191787 26185 slave.cpp:1172] Will retry registration in
42.550637ms if necessary
I0617 11:31:10.191942 26191 master.cpp:3154] Ignoring register slave message
from slave(43)@172.17.0.153:54856 (4ae164286465) as admission is already in
progress
I0617 11:31:10.235079 26193 leveldb.cpp:343] Persisting action (324 bytes) to
leveldb took 90.81634ms
I0617 11:31:10.235132 26193 replica.cpp:679] Persisted action at 3
I0617 11:31:10.235766 26180 slave.cpp:1172] Will retry registration in
5.219205ms if necessary
I0617 11:31:10.235883 26182 master.cpp:3154] Ignoring register slave message
from slave(43)@172.17.0.153:54856 (4ae164286465) as admission is already in
progress
I0617 11:31:10.235952 26186 replica.cpp:658] Replica received learned notice
for position 3
I0617 11:31:10.242194 26181 slave.cpp:1172] Will retry registration in
100.760739ms if necessary
I0617 11:31:10.242293 26180 master.cpp:3154] Ignoring register slave message
from slave(43)@172.17.0.153:54856 (4ae164286465) as admission is already in
progress
I0617 11:31:10.303081 26186 leveldb.cpp:343] Persisting action (326 bytes) to
leveldb took 67.092216ms
I0617 11:31:10.303145 26186 replica.cpp:679] Persisted action at 3
I0617 11:31:10.303179 26186 replica.cpp:664] Replica learned APPEND action at
position 3
I0617 11:31:10.304654 26193 registrar.cpp:490] Successfully updated the
'registry' in 163.563008ms
I0617 11:31:10.304949 26183 log.cpp:704] Attempting to truncate the log to 3
I0617 11:31:10.305080 26180 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 4
I0617 11:31:10.305426 26182 slave.cpp:2894] Received ping from
slave-observer(41)@172.17.0.153:54856
I0617 11:31:10.305599 26178 master.cpp:3223] Registered slave
20150617-113109-2566918572-54856-26159-S0 at slave(43)@172.17.0.153:54856
(4ae164286465) with cpus(*):1000; mem(*):1000; disk(*):3.70122e+06;
ports(*):[31000-32000]
I0617 11:31:10.305671 26180 hierarchical.hpp:496] Added slave
20150617-113109-2566918572-54856-26159-S0 (4ae164286465) with cpus(*):1000;
mem(*):1000; disk(*):3.70122e+06; ports(*):[31000-32000] (and cpus(*):1000;
mem(*):1000; disk(*):3.70122e+06; ports(*):[31000-32000] available)
I0617 11:31:10.305774 26182 slave.cpp:848] Registered with master
master@172.17.0.153:54856; given slave ID
20150617-113109-2566918572-54856-26159-S0
I0617 11:31:10.305802 26182 fetcher.cpp:77] Clearing fetcher cache
I0617 11:31:10.305886 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:10.305908 26180 hierarchical.hpp:852] Performed allocation for
slave 20150617-113109-2566918572-54856-26159-S0 in 188487ns
I0617 11:31:10.305987 26186 replica.cpp:511] Replica received write request for
position 4
I0617 11:31:10.305990 26191 status_update_manager.cpp:182] Resuming sending
status updates
I0617 11:31:10.306234 26182 slave.cpp:871] Checkpointing SlaveInfo to
'/tmp/FetcherCacheTest_SimpleEviction_avz3Qq/meta/slaves/20150617-113109-2566918572-54856-26159-S0/slave.info'
I0617 11:31:10.306622 26182 slave.cpp:906] Forwarding total oversubscribed
resources
I0617 11:31:10.306773 26180 master.cpp:3509] Received update of slave
20150617-113109-2566918572-54856-26159-S0 at slave(43)@172.17.0.153:54856
(4ae164286465) with total oversubscribed resources
I0617 11:31:10.306979 26180 hierarchical.hpp:570] Slave
20150617-113109-2566918572-54856-26159-S0 (4ae164286465) updated with
oversubscribed resources (total: cpus(*):1000; mem(*):1000;
disk(*):3.70122e+06; ports(*):[31000-32000], available: cpus(*):1000;
mem(*):1000; disk(*):3.70122e+06; ports(*):[31000-32000])
I0617 11:31:10.307106 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:10.307121 26180 hierarchical.hpp:852] Performed allocation for
slave 20150617-113109-2566918572-54856-26159-S0 in 105009ns
I0617 11:31:10.308487 26159 sched.cpp:155] Version: 0.23.0
I0617 11:31:10.308977 26185 sched.cpp:252] New master detected at
master@172.17.0.153:54856
I0617 11:31:10.309046 26185 sched.cpp:308] Authenticating with master
master@172.17.0.153:54856
I0617 11:31:10.309067 26185 sched.cpp:315] Using default CRAM-MD5 authenticatee
I0617 11:31:10.309231 26183 authenticatee.hpp:139] Creating new client SASL
connection
I0617 11:31:10.309468 26189 master.cpp:4187] Authenticating
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856
I0617 11:31:10.309623 26179 authenticator.cpp:406] Starting authentication
session for crammd5_authenticatee(134)@172.17.0.153:54856
I0617 11:31:10.309857 26185 authenticator.cpp:92] Creating new server SASL
connection
I0617 11:31:10.310057 26184 authenticatee.hpp:230] Received SASL authentication
mechanisms: CRAM-MD5
I0617 11:31:10.310093 26184 authenticatee.hpp:256] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0617 11:31:10.310211 26183 authenticator.cpp:197] Received SASL authentication
start
I0617 11:31:10.310261 26183 authenticator.cpp:319] Authentication requires more
steps
I0617 11:31:10.310353 26191 authenticatee.hpp:276] Received SASL authentication
step
I0617 11:31:10.310461 26191 authenticator.cpp:225] Received SASL authentication
step
I0617 11:31:10.310492 26191 auxprop.cpp:102] Request to lookup properties for
user: 'test-principal' realm: '4ae164286465' server FQDN: '4ae164286465'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0617 11:31:10.310503 26191 auxprop.cpp:174] Looking up auxiliary property
'*userPassword'
I0617 11:31:10.310551 26191 auxprop.cpp:174] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0617 11:31:10.310575 26191 auxprop.cpp:102] Request to lookup properties for
user: 'test-principal' realm: '4ae164286465' server FQDN: '4ae164286465'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0617 11:31:10.310585 26191 auxprop.cpp:124] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0617 11:31:10.310590 26191 auxprop.cpp:124] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0617 11:31:10.310603 26191 authenticator.cpp:311] Authentication success
I0617 11:31:10.310684 26183 authenticatee.hpp:316] Authentication success
I0617 11:31:10.310719 26181 master.cpp:4217] Successfully authenticated
principal 'test-principal' at
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856
I0617 11:31:10.310756 26189 authenticator.cpp:424] Authentication session
cleanup for crammd5_authenticatee(134)@172.17.0.153:54856
I0617 11:31:10.310919 26185 sched.cpp:396] Successfully authenticated with
master master@172.17.0.153:54856
I0617 11:31:10.310948 26185 sched.cpp:519] Sending registration request to
master@172.17.0.153:54856
I0617 11:31:10.311023 26185 sched.cpp:552] Will retry registration in
1.022304286secs if necessary
I0617 11:31:10.311106 26182 master.cpp:1714] Received registration request for
framework 'default' at
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856
W0617 11:31:10.311182 26182 master.cpp:1537] Framework at
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856
(authenticated as 'test-principal') does not specify principal in its
FrameworkInfo
I0617 11:31:10.311214 26182 master.cpp:1553] Authorizing framework principal ''
to receive offers for role '*'
I0617 11:31:10.311601 26185 master.cpp:1781] Registering framework
20150617-113109-2566918572-54856-26159-0000 (default) at
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856 with
checkpointing enabled and capabilities [ ]
I0617 11:31:10.311877 26191 hierarchical.hpp:354] Added framework
20150617-113109-2566918572-54856-26159-0000
I0617 11:31:10.311980 26193 sched.cpp:446] Framework registered with
20150617-113109-2566918572-54856-26159-0000
I0617 11:31:10.312029 26193 sched.cpp:460] Scheduler::registered took 24855ns
I0617 11:31:10.312314 26191 hierarchical.hpp:834] Performed allocation for 1
slaves in 409217ns
I0617 11:31:10.312620 26185 master.cpp:4106] Sending 1 offers to framework
20150617-113109-2566918572-54856-26159-0000 (default) at
scheduler-0f7a7d73-ebde-4f4c-8d6a-633826e0d607@172.17.0.153:54856
I0617 11:31:10.312891 26183 sched.cpp:609] Scheduler::resourceOffers took
18229ns
I0617 11:31:10.422061 26186 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 116.016895ms
I0617 11:31:10.422155 26186 replica.cpp:679] Persisted action at 4
I0617 11:31:10.422976 26185 replica.cpp:658] Replica received learned notice
for position 4
I0617 11:31:10.521113 26185 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 98.091629ms
I0617 11:31:10.521245 26185 leveldb.cpp:401] Deleting ~2 keys from leveldb took
62927ns
I0617 11:31:10.521267 26185 replica.cpp:679] Persisted action at 4
I0617 11:31:10.521298 26185 replica.cpp:664] Replica learned TRUNCATE action at
position 4
I0617 11:31:10.523082 26182 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:10.523124 26182 hierarchical.hpp:834] Performed allocation for 1
slaves in 378489ns
I0617 11:31:11.524418 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:11.524472 26180 hierarchical.hpp:834] Performed allocation for 1
slaves in 460020ns
I0617 11:31:12.525490 26188 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:12.525553 26188 hierarchical.hpp:834] Performed allocation for 1
slaves in 472737ns
I0617 11:31:13.527045 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:13.527099 26180 hierarchical.hpp:834] Performed allocation for 1
slaves in 484487ns
I0617 11:31:14.528139 26179 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:14.528198 26179 hierarchical.hpp:834] Performed allocation for 1
slaves in 479169ns
I0617 11:31:15.530030 26193 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:15.530084 26193 hierarchical.hpp:834] Performed allocation for 1
slaves in 496204ns
I0617 11:31:16.531805 26183 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:16.531859 26183 hierarchical.hpp:834] Performed allocation for 1
slaves in 532052ns
I0617 11:31:17.533004 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:17.533059 26180 hierarchical.hpp:834] Performed allocation for 1
slaves in 490802ns
I0617 11:31:18.533802 26182 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:18.533859 26182 hierarchical.hpp:834] Performed allocation for 1
slaves in 507850ns
I0617 11:31:19.535142 26192 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:19.535202 26192 hierarchical.hpp:834] Performed allocation for 1
slaves in 436193ns
I0617 11:31:20.536034 26186 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:20.536089 26186 hierarchical.hpp:834] Performed allocation for 1
slaves in 483814ns
I0617 11:31:21.537924 26193 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:21.537977 26193 hierarchical.hpp:834] Performed allocation for 1
slaves in 494807ns
I0617 11:31:22.539544 26180 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:22.539597 26180 hierarchical.hpp:834] Performed allocation for 1
slaves in 389674ns
I0617 11:31:23.540513 26186 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:23.540580 26186 hierarchical.hpp:834] Performed allocation for 1
slaves in 488999ns
I0617 11:31:24.541924 26182 hierarchical.hpp:933] No resources available to
allocate!
I0617 11:31:24.541970 26182 hierarchical.hpp:834] Performed allocation for 1
slaves in 415252ns
I0617 11:31:25.139394 26191 slave.cpp:4072] Querying resource estimator for
oversubscribable resources
I0617 11:31:25.139843 26185 slave.cpp:4086] Received oversubscribable resources
from the resource estimator
I0617 11:31:25.305955 26180 slave.cpp:2894] Received ping from
slave-observer(41)@172.17.0.153:54856
F0617 11:31:25.329059 26159 fetcher_cache_tests.cpp:359] CHECK_READY(offers):
is PENDING Failed to wait for resource offers
*** Check failure stack trace: ***
@ 0x7f84872c8578 google::LogMessage::Fail()
@ 0x7f84872c84bf google::LogMessage::SendToLog()
@ 0x7f84872c7e93 google::LogMessage::Flush()
@ 0x7f84872cb07c google::LogMessageFatal::~LogMessageFatal()
@ 0x9a57ea _CheckFatal::~_CheckFatal()
@ 0xb9c9d9 mesos::internal::tests::FetcherCacheTest::launchTask()
@ 0xba9e0e
mesos::internal::tests::FetcherCacheTest_SimpleEviction_Test::TestBody()
@ 0x1231e1f
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x122c991
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1212f7b testing::Test::Run()
@ 0x12136f3 testing::TestInfo::Run()
@ 0x1213c96 testing::TestCase::Run()
@ 0x1218e27 testing::internal::UnitTestImpl::RunAllTests()
@ 0x1232dcd
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x122d76a
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1217bea testing::UnitTest::Run()
@ 0xd1e81d main
@ 0x7f84825c5ec5 (unknown)
@ 0x886399 (unknown)
Makefile:7546: recipe for target 'check-local' failed
make[4]: Leaving directory '/mesos/mesos-0.23.0/_build/src'
make[4]: *** [check-local] Aborted
Makefile:6995: recipe for target 'check-am' failed
make[3]: Leaving directory '/mesos/mesos-0.23.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: *** [check] Error 2
Makefile:6999: recipe for target 'check' failed
make[2]: Leaving directory '/mesos/mesos-0.23.0/_build/src'
Makefile:668: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/mesos/mesos-0.23.0/_build'
Makefile:874: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
+ docker rmi mesos-1434539430-29652
Untagged: mesos-1434539430-29652:latest
Deleted: baab70daeeebd4c79c5de351a07c2be4eef86e2373c1bf21fe4e1a71c6c9ef1c
Deleted: 230ea6983f70e1557d21f542bac1140ab642df54026138609dd3294fe9a7415d
Deleted: 539b29ecc4235f5053e7903dd54e6898e62f1a3238a14377f62f6fc1445d94fb
Deleted: e382fa92848d646b5dc47ffa8e76e6e11819298bb24d6dfea15784db5c91530d
Deleted: bd0c7761ae9090b1d3c761304dde721c32227d99f5254f42caae54748d1ef3e7
Deleted: c2405f2c3681794d63f59c5e329955746fef3de4f20492e9d4565aea9ea8f41f
Deleted: 5c95f5cac2ab5aeb4cb73605a99ea543627fc751ef4c0e49e9719200de29bcdd
Deleted: 1b9aefe58c6797ac05b179b7bd6c6621f4a132fefff02546b1cd278ef1b7d0ce
Build step 'Execute shell' marked build as failure
