[
https://issues.apache.org/jira/browse/MESOS-2829?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Adam B updated MESOS-2829:
--------------------------
Labels: flaky-test mesosphere (was: flaky-test)
> FetcherCacheTest.CachedFallback test is flaky
> ---------------------------------------------
>
> Key: MESOS-2829
> URL: https://issues.apache.org/jira/browse/MESOS-2829
> Project: Mesos
> Issue Type: Bug
> Components: fetcher
> Affects Versions: 0.23.0
> Reporter: Vinod Kone
> Assignee: Bernd Mathiske
> Labels: flaky-test, mesosphere
>
> Observed this on internal CI
> {code}
> DEBUG: [ RUN ] FetcherCacheTest.CachedFallback
> DEBUG: Using temporary directory '/tmp/FetcherCacheTest_CachedFallback_dmr8bH'
> DEBUG: I0608 08:32:39.091538 56580 leveldb.cpp:176] Opened db in 5.078136ms
> DEBUG: I0608 08:32:39.093271 56580 leveldb.cpp:183] Compacted db in 1.696938ms
> DEBUG: I0608 08:32:39.093312 56580 leveldb.cpp:198] Created db iterator in
> 5708ns
> DEBUG: I0608 08:32:39.093325 56580 leveldb.cpp:204] Seeked to beginning of db
> in 940ns
> DEBUG: I0608 08:32:39.093333 56580 leveldb.cpp:273] Iterated through 0 keys
> in the db in 270ns
> DEBUG: I0608 08:32:39.093353 56580 replica.cpp:744] Replica recovered with
> log positions 0 -> 0 with 1 holes and 0 unlearned
> DEBUG: I0608 08:32:39.093597 56613 recover.cpp:449] Starting replica recovery
> DEBUG: I0608 08:32:39.093706 56597 recover.cpp:475] Replica is in EMPTY status
> DEBUG: I0608 08:32:39.094511 56607 replica.cpp:641] Replica in EMPTY status
> received a broadcasted recover request
> DEBUG: I0608 08:32:39.094952 56606 recover.cpp:195] Received a recover
> response from a replica in EMPTY status
> DEBUG: I0608 08:32:39.095067 56606 recover.cpp:566] Updating replica status
> to STARTING
> DEBUG: I0608 08:32:39.095487 56598 master.cpp:363] Master
> 20150608-083239-1787367596-39187-56580 (<***redacted***>) started on
> <***redacted***>
> DEBUG: I0608 08:32:39.095510 56598 master.cpp:365] Flags at startup:
> --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="true" --authenticate_slaves="true" --authenticators="crammd5"
> --credentials="/tmp/FetcherCacheTest_CachedFallback_dmr8bH/credentials"
> --framework_sorter="drf" --help="false" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --quiet="false" --recovery_slave_removal_limit="100%"
> --registry="replicated_log" --registry_fetch_timeout="1mins"
> --registry_store_timeout="25secs" --registry_strict="true"
> --root_submissions="true" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/usr/local/share/mesos/webui"
> --work_dir="/tmp/FetcherCacheTest_CachedFallback_dmr8bH/master"
> --zk_session_timeout="10secs"
> DEBUG: I0608 08:32:39.095630 56598 master.cpp:410] Master only allowing
> authenticated frameworks to register
> DEBUG: I0608 08:32:39.095636 56598 master.cpp:415] Master only allowing
> authenticated slaves to register
> DEBUG: I0608 08:32:39.095643 56598 credentials.hpp:37] Loading credentials
> for authentication from
> '/tmp/FetcherCacheTest_CachedFallback_dmr8bH/credentials'
> DEBUG: I0608 08:32:39.095743 56598 master.cpp:454] Using default 'crammd5'
> authenticator
> DEBUG: I0608 08:32:39.095782 56598 master.cpp:491] Authorization enabled
> DEBUG: I0608 08:32:39.096765 56606 leveldb.cpp:306] Persisting metadata (8
> bytes) to leveldb took 1.654474ms
> DEBUG: I0608 08:32:39.096788 56606 replica.cpp:323] Persisted replica status
> to STARTING
> DEBUG: I0608 08:32:39.096925 56612 master.cpp:1474] The newly elected leader
> is master@<***redacted***> with id 20150608-083239-1787367596-39187-56580
> DEBUG: I0608 08:32:39.096940 56612 master.cpp:1487] Elected as the leading
> master!
> DEBUG: I0608 08:32:39.096946 56612 master.cpp:1257] Recovering from registrar
> DEBUG: I0608 08:32:39.097033 56608 registrar.cpp:313] Recovering registrar
> DEBUG: I0608 08:32:39.097095 56616 recover.cpp:475] Replica is in STARTING
> status
> DEBUG: I0608 08:32:39.097718 56607 replica.cpp:641] Replica in STARTING
> status received a broadcasted recover request
> DEBUG: I0608 08:32:39.097949 56602 recover.cpp:195] Received a recover
> response from a replica in STARTING status
> DEBUG: I0608 08:32:39.098423 56609 recover.cpp:566] Updating replica status
> to VOTING
> DEBUG: I0608 08:32:39.099462 56609 leveldb.cpp:306] Persisting metadata (8
> bytes) to leveldb took 962629ns
> DEBUG: I0608 08:32:39.099488 56609 replica.cpp:323] Persisted replica status
> to VOTING
> DEBUG: I0608 08:32:39.099527 56609 recover.cpp:580] Successfully joined the
> Paxos group
> DEBUG: I0608 08:32:39.099577 56609 recover.cpp:464] Recover process terminated
> DEBUG: I0608 08:32:39.099874 56601 log.cpp:661] Attempting to start the writer
> DEBUG: I0608 08:32:39.101229 56611 replica.cpp:477] Replica received implicit
> promise request with proposal 1
> DEBUG: I0608 08:32:39.102149 56611 leveldb.cpp:306] Persisting metadata (8
> bytes) to leveldb took 894488ns
> DEBUG: I0608 08:32:39.102175 56611 replica.cpp:345] Persisted promised to 1
> DEBUG: I0608 08:32:39.102978 56612 coordinator.cpp:230] Coordinator attemping
> to fill missing position
> DEBUG: I0608 08:32:39.104015 56612 replica.cpp:378] Replica received explicit
> promise request for position 0 with proposal 2
> DEBUG: I0608 08:32:39.105124 56612 leveldb.cpp:343] Persisting action (8
> bytes) to leveldb took 1.08594ms
> DEBUG: I0608 08:32:39.105156 56612 replica.cpp:679] Persisted action at 0
> DEBUG: I0608 08:32:39.106197 56596 replica.cpp:511] Replica received write
> request for position 0
> DEBUG: I0608 08:32:39.106230 56596 leveldb.cpp:438] Reading position from
> leveldb took 16312ns
> DEBUG: I0608 08:32:39.107139 56596 leveldb.cpp:343] Persisting action (14
> bytes) to leveldb took 891559ns
> DEBUG: I0608 08:32:39.107167 56596 replica.cpp:679] Persisted action at 0
> DEBUG: I0608 08:32:39.107656 56601 replica.cpp:658] Replica received learned
> notice for position 0
> DEBUG: I0608 08:32:39.109146 56601 leveldb.cpp:343] Persisting action (16
> bytes) to leveldb took 1.459405ms
> DEBUG: I0608 08:32:39.109180 56601 replica.cpp:679] Persisted action at 0
> DEBUG: I0608 08:32:39.109194 56601 replica.cpp:664] Replica learned NOP
> action at position 0
> DEBUG: I0608 08:32:39.109606 56604 log.cpp:677] Writer started with ending
> position 0
> DEBUG: I0608 08:32:39.109839 56613 leveldb.cpp:438] Reading position from
> leveldb took 17622ns
> DEBUG: I0608 08:32:39.111635 56600 registrar.cpp:346] Successfully fetched
> the registry (0B) in 14.553088ms
> DEBUG: I0608 08:32:39.111667 56600 registrar.cpp:445] Applied 1 operations in
> 2865ns; attempting to update the 'registry'
> DEBUG: I0608 08:32:39.112975 56601 log.cpp:685] Attempting to append 157
> bytes to the log
> DEBUG: I0608 08:32:39.113034 56608 coordinator.cpp:340] Coordinator
> attempting to write APPEND action at position 1
> DEBUG: I0608 08:32:39.113862 56595 replica.cpp:511] Replica received write
> request for position 1
> DEBUG: I0608 08:32:39.114780 56595 leveldb.cpp:343] Persisting action (176
> bytes) to leveldb took 885199ns
> DEBUG: I0608 08:32:39.114806 56595 replica.cpp:679] Persisted action at 1
> DEBUG: I0608 08:32:39.115378 56610 replica.cpp:658] Replica received learned
> notice for position 1
> DEBUG: I0608 08:32:39.116317 56610 leveldb.cpp:343] Persisting action (178
> bytes) to leveldb took 915397ns
> DEBUG: I0608 08:32:39.116343 56610 replica.cpp:679] Persisted action at 1
> DEBUG: I0608 08:32:39.116356 56610 replica.cpp:664] Replica learned APPEND
> action at position 1
> DEBUG: I0608 08:32:39.117009 56615 registrar.cpp:490] Successfully updated
> the 'registry' in 4.941824ms
> DEBUG: I0608 08:32:39.117075 56615 registrar.cpp:376] Successfully recovered
> registrar
> DEBUG: I0608 08:32:39.117147 56615 master.cpp:1284] Recovered 0 slaves from
> the Registry (119B) ; allowing 10mins for slaves to re-register
> DEBUG: I0608 08:32:39.117447 56614 log.cpp:704] Attempting to truncate the
> log to 1
> DEBUG: I0608 08:32:39.117504 56613 coordinator.cpp:340] Coordinator
> attempting to write TRUNCATE action at position 2
> DEBUG: I0608 08:32:39.118638 56608 replica.cpp:511] Replica received write
> request for position 2
> DEBUG: I0608 08:32:39.119679 56608 leveldb.cpp:343] Persisting action (16
> bytes) to leveldb took 1.017833ms
> DEBUG: I0608 08:32:39.119707 56608 replica.cpp:679] Persisted action at 2
> DEBUG: I0608 08:32:39.120102 56608 replica.cpp:658] Replica received learned
> notice for position 2
> DEBUG: I0608 08:32:39.121323 56608 leveldb.cpp:343] Persisting action (18
> bytes) to leveldb took 1.201996ms
> DEBUG: I0608 08:32:39.121378 56608 leveldb.cpp:401] Deleting ~1 keys from
> leveldb took 22912ns
> DEBUG: I0608 08:32:39.121395 56608 replica.cpp:679] Persisted action at 2
> DEBUG: I0608 08:32:39.121404 56608 replica.cpp:664] Replica learned TRUNCATE
> action at position 2
> DEBUG: I0608 08:32:39.129438 56580 containerizer.cpp:111] Using isolation:
> posix/cpu,posix/mem
> DEBUG: I0608 08:32:39.132251 56615 slave.cpp:188] Slave started on
> 40)@<***redacted***>
> DEBUG: I0608 08:32:39.132272 56615 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_CachedFallback_P03rUd/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"
> --egress_unique_flow_per_container="false"
> --enforce_container_disk_quota="false" --ephemeral_ports_per_container="1024"
> --executor_registration_timeout="1mins"
> --executor_shutdown_grace_period="5secs"
> --fetcher_cache_dir="/tmp/FetcherCacheTest_CachedFallback_P03rUd/fetch"
> --fetcher_cache_size="2GB" --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="/builddir/build/BUILD/mesos-0.23.0/src" --logbufsecs="0"
> --logging_level="INFO" --network_enable_socket_statistics_details="false"
> --network_enable_socket_statistics_summary="false"
> --oversubscribed_resources_interval="15secs" --perf_duration="10secs"
> --perf_interval="1mins" --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_CachedFallback_P03rUd"
> DEBUG: I0608 08:32:39.132558 56615 credentials.hpp:85] Loading credential for
> authentication from '/tmp/FetcherCacheTest_CachedFallback_P03rUd/credential'
> DEBUG: I0608 08:32:39.132689 56615 slave.cpp:319] Slave using credential for:
> test-principal
> DEBUG: I0608 08:32:39.132869 56615 slave.cpp:352] Slave resources:
> cpus(*):1000; mem(*):1000; disk(*):464332; ports(*):[31000-32000]
> DEBUG: I0608 08:32:39.133673 56615 slave.cpp:382] Slave hostname:
> <***redacted***>
> DEBUG: I0608 08:32:39.133689 56615 slave.cpp:387] Slave checkpoint: true
> DEBUG: I0608 08:32:39.133949 56596 state.cpp:35] Recovering state from
> '/tmp/FetcherCacheTest_CachedFallback_P03rUd/meta'
> DEBUG: I0608 08:32:39.134446 56595 status_update_manager.cpp:201] Recovering
> status update manager
> DEBUG: I0608 08:32:39.134539 56609 containerizer.cpp:312] Recovering
> containerizer
> DEBUG: I0608 08:32:39.135972 56616 slave.cpp:3950] Finished recovery
> DEBUG: I0608 08:32:39.136243 56616 slave.cpp:4104] Querying resource
> estimator for oversubscribable resources
> DEBUG: I0608 08:32:39.136576 56599 status_update_manager.cpp:175] Pausing
> sending status updates
> DEBUG: I0608 08:32:39.136595 56602 slave.cpp:678] New master detected at
> master@<***redacted***>
> DEBUG: I0608 08:32:39.136620 56602 slave.cpp:741] Authenticating with master
> master@<***redacted***>
> DEBUG: I0608 08:32:39.136628 56602 slave.cpp:746] Using default CRAM-MD5
> authenticatee
> DEBUG: I0608 08:32:39.136670 56602 slave.cpp:714] Detecting new master
> DEBUG: I0608 08:32:39.136698 56602 slave.cpp:4125] Received oversubscribable
> resources from the resource estimator
> DEBUG: I0608 08:32:39.136703 56602 slave.cpp:4129] No master detected.
> Re-querying resource estimator after 15secs
> DEBUG: I0608 08:32:39.136778 56618 authenticatee.hpp:139] Creating new client
> SASL connection
> DEBUG: I0608 08:32:39.137074 56618 master.cpp:4167] Authenticating
> slave(40)@<***redacted***>
> DEBUG: I0608 08:32:39.137186 56611 authenticator.cpp:92] Creating new server
> SASL connection
> DEBUG: I0608 08:32:39.137485 56605 authenticatee.hpp:230] Received SASL
> authentication mechanisms: CRAM-MD5
> DEBUG: I0608 08:32:39.137503 56605 authenticatee.hpp:256] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> DEBUG: I0608 08:32:39.137540 56605 authenticator.cpp:197] Received SASL
> authentication start
> DEBUG: I0608 08:32:39.137615 56605 authenticator.cpp:319] Authentication
> requires more steps
> DEBUG: I0608 08:32:39.137645 56605 authenticatee.hpp:276] Received SASL
> authentication step
> DEBUG: I0608 08:32:39.137817 56609 authenticator.cpp:225] Received SASL
> authentication step
> DEBUG: I0608 08:32:39.137866 56609 authenticator.cpp:311] Authentication
> success
> DEBUG: I0608 08:32:39.137989 56609 master.cpp:4197] Successfully
> authenticated principal 'test-principal' at slave(40)@<***redacted***>
> DEBUG: I0608 08:32:39.138123 56601 authenticatee.hpp:316] Authentication
> success
> DEBUG: I0608 08:32:39.138363 56601 slave.cpp:812] Successfully authenticated
> with master master@<***redacted***>
> DEBUG: I0608 08:32:39.138468 56596 master.cpp:3149] Registering slave at
> slave(40)@<***redacted***> (<***redacted***>) with id
> 20150608-083239-1787367596-39187-56580-S0
> DEBUG: I0608 08:32:39.138628 56614 registrar.cpp:445] Applied 1 operations in
> 11844ns; attempting to update the 'registry'
> DEBUG: I0608 08:32:39.140049 56596 log.cpp:685] Attempting to append 351
> bytes to the log
> DEBUG: I0608 08:32:39.140110 56600 coordinator.cpp:340] Coordinator
> attempting to write APPEND action at position 3
> DEBUG: I0608 08:32:39.141154 56596 replica.cpp:511] Replica received write
> request for position 3
> DEBUG: I0608 08:32:39.142328 56596 leveldb.cpp:343] Persisting action (370
> bytes) to leveldb took 1.151559ms
> DEBUG: I0608 08:32:39.142359 56596 replica.cpp:679] Persisted action at 3
> DEBUG: I0608 08:32:39.142838 56607 replica.cpp:658] Replica received learned
> notice for position 3
> DEBUG: I0608 08:32:39.144026 56607 leveldb.cpp:343] Persisting action (372
> bytes) to leveldb took 1.167494ms
> DEBUG: I0608 08:32:39.144060 56607 replica.cpp:679] Persisted action at 3
> DEBUG: I0608 08:32:39.144073 56607 replica.cpp:664] Replica learned APPEND
> action at position 3
> DEBUG: I0608 08:32:39.144342 56595 registrar.cpp:490] Successfully updated
> the 'registry' in 5696us
> DEBUG: I0608 08:32:39.144366 56607 log.cpp:704] Attempting to truncate the
> log to 3
> DEBUG: I0608 08:32:39.144423 56614 coordinator.cpp:340] Coordinator
> attempting to write TRUNCATE action at position 4
> DEBUG: I0608 08:32:39.144631 56607 master.cpp:3206] Registered slave
> 20150608-083239-1787367596-39187-56580-S0 at slave(40)@<***redacted***>
> (<***redacted***>) with cpus(*):1000; mem(*):1000; disk(*):464332;
> ports(*):[31000-32000]
> DEBUG: I0608 08:32:39.144726 56617 hierarchical.hpp:496] Added slave
> 20150608-083239-1787367596-39187-56580-S0 (<***redacted***>) with
> cpus(*):1000; mem(*):1000; disk(*):464332; ports(*):[31000-32000] (and
> cpus(*):1000; mem(*):1000; disk(*):464332; ports(*):[31000-32000] available)
> DEBUG: I0608 08:32:39.144745 56609 slave.cpp:846] Registered with master
> master@<***redacted***>; given slave ID
> 20150608-083239-1787367596-39187-56580-S0
> DEBUG: I0608 08:32:39.144872 56612 status_update_manager.cpp:182] Resuming
> sending status updates
> DEBUG: I0608 08:32:39.145023 56602 replica.cpp:511] Replica received write
> request for position 4
> DEBUG: GMOCK WARNING:
> DEBUG: Uninteresting mock function call - returning directly.
> DEBUG: Function call: resourceOffers(0x3754860, @0x7fad6e321b30 {
> 128-byte object <50-DB E0-80 AD-7F 00-00 00-00 00-00 00-00 00-00 D0-78 00-04
> AD-7F 00-00 70-79 00-04 AD-7F 00-00 10-7A 00-04 AD-7F 00-00 B0-7A 00-04 AD-7F
> 00-00 A0-6B 00-04 AD-7F 00-00 04-00 00-00 04-00 00-00 04-00 00-00 00-00 00-00
> 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00
> 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00
> 0F-00 00-00> })
> DEBUG: Stack trace:
> DEBUG: I0608 08:32:39.146750 56580 sched.cpp:157] Version: 0.23.0-rc0
> DEBUG: I0608 08:32:39.147104 56613 sched.cpp:254] New master detected at
> master@<***redacted***>
> DEBUG: I0608 08:32:39.147121 56613 sched.cpp:310] Authenticating with master
> master@<***redacted***>
> DEBUG: I0608 08:32:39.147128 56613 sched.cpp:317] Using default CRAM-MD5
> authenticatee
> DEBUG: I0608 08:32:39.147186 56613 authenticatee.hpp:139] Creating new client
> SASL connection
> DEBUG: I0608 08:32:39.147374 56595 master.cpp:4167] Authenticating
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> DEBUG: I0608 08:32:39.147596 56595 authenticator.cpp:92] Creating new server
> SASL connection
> DEBUG: I0608 08:32:39.147666 56595 authenticatee.hpp:230] Received SASL
> authentication mechanisms: CRAM-MD5
> DEBUG: I0608 08:32:39.147678 56595 authenticatee.hpp:256] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> DEBUG: I0608 08:32:39.147701 56595 authenticator.cpp:197] Received SASL
> authentication start
> DEBUG: I0608 08:32:39.147738 56595 authenticator.cpp:319] Authentication
> requires more steps
> DEBUG: I0608 08:32:39.147789 56615 authenticatee.hpp:276] Received SASL
> authentication step
> DEBUG: I0608 08:32:39.147853 56615 authenticator.cpp:225] Received SASL
> authentication step
> DEBUG: I0608 08:32:39.147881 56615 authenticator.cpp:311] Authentication
> success
> DEBUG: I0608 08:32:39.147923 56615 authenticatee.hpp:316] Authentication
> success
> DEBUG: I0608 08:32:39.148066 56617 master.cpp:4197] Successfully
> authenticated principal 'test-principal' at
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> DEBUG: I0608 08:32:39.148905 56598 sched.cpp:398] Successfully authenticated
> with master master@<***redacted***>
> DEBUG: I0608 08:32:39.149000 56608 master.cpp:1714] Received registration
> request for framework 'default' at
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> DEBUG: W0608 08:32:39.149026 56608 master.cpp:1537] Framework at
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> (authenticated as 'test-principal') does not specify principal in its
> FrameworkInfo
> DEBUG: I0608 08:32:39.149039 56608 master.cpp:1553] Authorizing framework
> principal '' to receive offers for role '*'
> DEBUG: I0608 08:32:39.149502 56618 master.cpp:1781] Registering framework
> 20150608-083239-1787367596-39187-56580-0000 (default) at
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> DEBUG: I0608 08:32:39.149587 56618 hierarchical.hpp:354] Added framework
> 20150608-083239-1787367596-39187-56580-0000
> DEBUG: I0608 08:32:39.149668 56614 sched.cpp:448] Framework registered with
> 20150608-083239-1787367596-39187-56580-0000
> DEBUG: I0608 08:32:39.149765 56615 master.cpp:4086] Sending 1 offers to
> framework 20150608-083239-1787367596-39187-56580-0000 (default) at
> scheduler-4b62df2f-7cdc-427c-8301-0e282d99bc06@<***redacted***>
> DEBUG: I0608 08:32:39.153530 56602 leveldb.cpp:343] Persisting action (16
> bytes) to leveldb took 8.487561ms
> DEBUG: I0608 08:32:39.153561 56602 replica.cpp:679] Persisted action at 4
> DEBUG: I0608 08:32:39.154438 56603 replica.cpp:658] Replica received learned
> notice for position 4
> DEBUG: I0608 08:32:39.155531 56603 leveldb.cpp:343] Persisting action (18
> bytes) to leveldb took 1.074345ms
> DEBUG: I0608 08:32:39.155571 56603 leveldb.cpp:401] Deleting ~2 keys from
> leveldb took 16036ns
> DEBUG: I0608 08:32:39.155580 56603 replica.cpp:679] Persisted action at 4
> DEBUG: I0608 08:32:39.155587 56603 replica.cpp:664] Replica learned TRUNCATE
> action at position 4
> DEBUG: I0608 08:32:54.136912 56596 slave.cpp:4104] Querying resource
> estimator for oversubscribable resources
> DEBUG: I0608 08:32:54.137251 56611 slave.cpp:4125] Received oversubscribable
> resources from the resource estimator
> DEBUG: F0608 08:32:54.154590 56580 fetcher_cache_tests.cpp:354]
> CHECK_READY(offers): is PENDING Failed to wait for resource offers
> DEBUG: *** Check failure stack trace: ***
> DEBUG: @ 0x7fad806b9f1d google::LogMessage::Fail()
> DEBUG: @ 0x7fad806bbd5d google::LogMessage::SendToLog()
> DEBUG: @ 0x7fad806b9b0c google::LogMessage::Flush()
> DEBUG: @ 0x7fad806bc659 google::LogMessageFatal::~LogMessageFatal()
> DEBUG: @ 0x53d858 _CheckFatal::~_CheckFatal()
> DEBUG: @ 0x66c14f
> mesos::internal::tests::FetcherCacheTest::launchTask()
> DEBUG: @ 0x66f9e9
> mesos::internal::tests::FetcherCacheTest_CachedFallback_Test::TestBody()
> DEBUG: @ 0xba34d3
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> DEBUG: @ 0xb9a777 testing::Test::Run()
> DEBUG: @ 0xb9a81e testing::TestInfo::Run()
> DEBUG: @ 0xb9a925 testing::TestCase::Run()
> DEBUG: @ 0xb9abc8
> testing::internal::UnitTestImpl::RunAllTests()
> DEBUG: @ 0xb9ae67 testing::UnitTest::Run()
> DEBUG: @ 0x4a1e73 main
> DEBUG: @ 0x7fad7e4e2d5d __libc_start_main
> DEBUG: @ 0x4acf79 (unknown)
> DEBUG: make[3]: *** [check-local] Aborted (core dumped)
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
