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