Music app cannot read from cache on /e/
- /e/ version: 0.7-o-2020012438713-dev-dumpling
- Device model: One+ 5T
- When it started to occur: when I started using the application UltraSonic
- Reproducible with the last /e/ version: YES
- Reproducible with LineageOS: Don't know
Summary
https://community.e.foundation/t/ultrasonic-cached-files-are-not-read/11701
The problem
Install UltraSonic app from F-Droid and connect to any SubSonic API compatible server. The first song will play fine as it downloads it. Any further attempts, which should be uplifting the cached file, are failing. The file is present in the cache and readable by VLC.
Technical informations
Relevant logs (adb logcat
)
This is what got logged when attempting to replay the song:
02-06 19:10:28.029 28970 28970 I chatty : uid=10172(org.moire.ultrasonic) identical 11 lines
02-06 19:10:29.045 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 10. Total Items:25
02-06 19:10:29.078 28970 28970 I CancellableTask: Cancelling BufferTask (DownloadFile (Entry(id=660, parent=, isDirectory=false, title=Gimme Some Lovin', album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=2, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=3766337, songCount=null, duration=184, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:00 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.078 28970 28970 I DownloadServiceImpl: IDLE -> IDLE (null)
02-06 19:10:29.087 28970 28970 V MediaPlayer: resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
02-06 19:10:29.087 28970 28970 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
02-06 19:10:29.095 28970 28970 I DownloadServiceImpl: In updateRemoteControl, playerState: IDLE [0]
02-06 19:10:29.101 28970 29209 I DownloadServiceLifecycleSupport: Serialized currentPlayingIndex: -1, currentPlayingPosition: 0
02-06 19:10:29.104 28970 29209 I FileUtil: Serialized object to /data/user/0/org.moire.ultrasonic/cache/downloadstate.ser
02-06 19:10:29.113 28970 28970 I DownloadServiceImpl: In updateRemoteControl, playerState: IDLE [0]
02-06 19:10:29.120 28970 28970 I DownloadServiceImpl: In updateRemoteControl, playerState: IDLE [0]
02-06 19:10:29.146 28970 28970 W Notification: Use of stream types is deprecated for operations other than volume control
02-06 19:10:29.146 28970 28970 W Notification: See the documentation of setSound() for what to use instead with android.media.AudioAttributes to qualify your playback use case
02-06 19:10:29.150 28970 28970 I CancellableTask: Cancelling BufferTask (DownloadFile (Entry(id=660, parent=, isDirectory=false, title=Gimme Some Lovin', album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=2, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=3766337, songCount=null, duration=184, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:00 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.150 28970 28970 I DownloadServiceImpl: IDLE -> IDLE (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.152 28970 28970 V MediaPlayer: resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
02-06 19:10:29.152 28970 28970 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
02-06 19:10:29.153 28970 28970 I DownloadServiceImpl: Buffering from position 0 and bitrate -1
02-06 19:10:29.154 28970 29250 I CancellableTask: Starting thread for BufferTask (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.158 1265 1265 D ZenLog : intercepted: 0|org.moire.ultrasonic|3033|null|10172,!priority
02-06 19:10:29.159 28970 29213 I DownloadServiceLifecycleSupport: Serialized currentPlayingIndex: 0, currentPlayingPosition: 0
02-06 19:10:29.161 28970 29250 I DownloadServiceImpl: IDLE -> DOWNLOADING (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.161 28970 29213 I FileUtil: Serialized object to /data/user/0/org.moire.ultrasonic/cache/downloadstate.ser
02-06 19:10:29.165 28970 29250 I DownloadServiceImpl: Buffering /storage/emulated/0/Android/data/org.moire.ultrasonic/music/Blues Brothers/The Very Best of the Blues Brothers/01-Everybody Needs Somebody to Love.partial.ogg (0/100000, true)
02-06 19:10:29.166 28970 29250 V MediaPlayer: resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
02-06 19:10:29.166 28970 29250 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
02-06 19:10:29.166 28970 29250 I DownloadServiceImpl: DOWNLOADING -> IDLE (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.169 28970 29250 W MediaPlayer: Use of stream types is deprecated for operations other than volume control
02-06 19:10:29.169 28970 29250 W MediaPlayer: See the documentation of setAudioStreamType() for what to use instead with android.media.AudioAttributes to qualify your playback use case
02-06 19:10:29.170 28970 29250 I DownloadServiceImpl: Preparing media player
02-06 19:10:29.171 871 871 D NuPlayerDriver: NuPlayerDriver(0xedfc4310) created, clientPid(28970)
02-06 19:10:29.174 3578 3578 D NotificationRepository: updateNotification() called with: list = [15]
02-06 19:10:29.182 28970 29250 I DownloadServiceImpl: IDLE -> PREPARING (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.185 3578 3578 D NotificationRepository: updateNotification() called with: list = [15]
02-06 19:10:29.194 28970 29250 I CancellableTask: Stopping thread for BufferTask (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.194 871 29252 D GenericSource: FileSource remote
02-06 19:10:29.195 871 29252 W GenericSource: extractor service cannot make data source
02-06 19:10:29.195 871 29252 D GenericSource: FileSource local
02-06 19:10:29.215 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 10. Total Items:25
02-06 19:10:29.235 2417 2417 W StaticLayout: maxLineHeight should not be -1. maxLines:1 lineCount:1
02-06 19:10:29.236 2417 2417 I chatty : uid=10020(com.android.systemui) identical 1 line
02-06 19:10:29.236 2417 2417 W StaticLayout: maxLineHeight should not be -1. maxLines:1 lineCount:1
02-06 19:10:29.286 871 29251 D NuPlayerDriver: notifyListener_l(0xedfc4310), (1, 0, 0, -1), loop setting(0, 0)
02-06 19:10:29.290 28970 28996 I DownloadServiceImpl: Media player prepared
02-06 19:10:29.291 28970 28996 I DownloadServiceImpl: PREPARING -> PREPARED (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.295 1265 10566 V MediaRouterService: restoreBluetoothA2dp(false)
02-06 19:10:29.295 851 6244 D AudioPolicyManagerCustom: setForceUse() usage 1, config 10, mPhoneState 0
02-06 19:10:29.296 871 871 D NuPlayerDriver: start(0xedfc4310), state is 4, eos is 0
02-06 19:10:29.296 871 29251 I GenericSource: start
02-06 19:10:29.297 28970 28996 I DownloadServiceImpl: PREPARED -> STARTED (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:29.301 28970 28996 I DownloadServiceImpl: In updateRemoteControl, playerState: STARTED [0]
02-06 19:10:29.302 851 6244 I AudioFlinger: openOutput() this 0xe833d000, module 10 Device 2, SamplingRate 44100, Format 0x7000000, Channels 3, flags 31
02-06 19:10:29.302 738 1383 D audio_hw_primary: adev_open_output_stream: enter: format(0x7000000) sample_rate(44100) channel_mask(0x3) devices(0x2) flags(0x31) stream_handle(0xecf0b000)
02-06 19:10:29.303 738 1383 D audio_hw_primary: adev_open_output_stream: Stream (0xecf0b000) picks up usecase (compress-offload-playback)
02-06 19:10:29.304 851 6244 I AudioFlinger: HAL output buffer size 32768 frames, normal sink buffer size 32768 frames
02-06 19:10:29.306 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: Using Fix DSP delay
02-06 19:10:29.306 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: delay in ms is 50
02-06 19:10:29.306 851 29257 I AudioFlinger: AudioFlinger's thread 0xe5ecd000 tid=29257 ready to run
02-06 19:10:29.306 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: Using Fix DSP delay
02-06 19:10:29.306 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: delay in ms is 50
02-06 19:10:29.311 851 851 W AudioFlinger: moveEffectChain_l() effect chain for session 0 not on source thread 0xe7903080
02-06 19:10:29.311 738 1383 D audio_hw_primary: out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: routing=2
02-06 19:10:29.311 738 1383 D amplifier_oneplus5: amp_out_set_parameters
02-06 19:10:29.311 738 1383 D amplifier_oneplus5: set_anc_parameter
02-06 19:10:29.312 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: Using Fix DSP delay
02-06 19:10:29.312 738 1383 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: delay in ms is 50
02-06 19:10:29.314 738 1383 E audio_hw_primary: out_set_volume: compress mute 0
02-06 19:10:29.315 738 1383 E audio_hw_primary: out_set_compr_volume:ctl for mixer cmd - Compress Playback 8 Volume, left 0.031623, right 0.031623
02-06 19:10:29.316 738 1383 D audio_hw_primary: out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: music_offload_sample_rate=44100
02-06 19:10:29.316 738 1383 D amplifier_oneplus5: amp_out_set_parameters
02-06 19:10:29.316 738 1383 D amplifier_oneplus5: set_anc_parameter
02-06 19:10:29.334 28970 28996 W Notification: Use of stream types is deprecated for operations other than volume control
02-06 19:10:29.334 28970 28996 W Notification: See the documentation of setSound() for what to use instead with android.media.AudioAttributes to qualify your playback use case
02-06 19:10:29.343 28970 29224 I DownloadServiceLifecycleSupport: Serialized currentPlayingIndex: 0, currentPlayingPosition: 0
02-06 19:10:29.347 28970 29224 I FileUtil: Serialized object to /data/user/0/org.moire.ultrasonic/cache/downloadstate.ser
02-06 19:10:29.352 28970 28970 D PullToRefresh: onSizeChanged. W: 1080, H: 1501
02-06 19:10:29.352 28970 28970 D PullToRefresh: Setting Padding. L: 0, T: -901, R: 0, B: 0
02-06 19:10:29.357 738 1383 E audio_hw_primary: out_set_volume: compress mute 0
02-06 19:10:29.357 738 1383 E audio_hw_primary: out_set_compr_volume:ctl for mixer cmd - Compress Playback 8 Volume, left 0.031623, right 0.031623
02-06 19:10:29.357 3578 3578 D NotificationRepository: updateNotification() called with: list = [15]
02-06 19:10:29.359 738 29261 D audio_hw_primary: start_output_stream: enter: stream(0xecf0b000)usecase(3: compress-offload-playback) devices(0x2)
02-06 19:10:29.360 738 29261 E audio_hw_extn: audio_extn_perf_lock_acquire: Failed to acquire perf lock, err: -1
02-06 19:10:29.360 738 29261 D audio_hw_primary: select_devices for use case (compress-offload-playback)
02-06 19:10:29.360 738 29261 D audio_hw_primary: select_devices: out_snd_device(2: speaker) in_snd_device(0: )
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_check_and_set_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 44100 channels 2, backend_idx 0 usecase = 3 device (speaker)
02-06 19:10:29.360 738 29261 D msm8974_platform: platform_split_snd_device: snd_device(2) num devices(1) new_snd_devices(0)
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_check_and_set_codec_backend_cfg: new_snd_devices[0] is 2
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 44100 channels 2, backend_idx 0 usecase = 3 device (speaker)
02-06 19:10:29.360 738 29261 D msm8974_platform: platform_check_codec_backend_cfg:becf: afe: playback on codec device not supporting native playback set default Sample Rate(48k)
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: Codec selected backend: 0 updated bit width: 16 and sample rate: 48000
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: Codec backend needs to be updated. new bit width: 16new sample rate: 48000 new channels: 2
02-06 19:10:29.360 738 29261 I msm8974_platform: platform_set_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2 format 117440512, backend_idx 0 device (speaker)
02-06 19:10:29.361 738 29261 D msm8974_platform: platform_set_codec_backend_cfg:becf: afe: SLIM_0_RX Format mixer set to 16 bit for 7000000 format
02-06 19:10:29.361 738 29261 D audio_hw_primary: check_usecases_codec_backend:becf: force routing 1
02-06 19:10:29.361 738 29261 E msm8974_platform: platform_check_backends_match: Invalid snd_device =
02-06 19:10:29.361 738 29261 D audio_hw_primary: check_usecases_codec_backend:becf: (61) check_usecases curr device: speaker, usecase device: backends match 0
02-06 19:10:29.361 738 29261 D audio_hw_primary: check_usecases_codec_backend:becf: check_usecases num.of Usecases to switch 0
02-06 19:10:29.361 738 29261 D hardware_info: hw_info_append_hw_type : device_name = speaker
02-06 19:10:29.361 738 29261 D msm8974_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0)
02-06 19:10:29.361 738 29261 D audio_hw_primary: enable_snd_device: snd_device(2: speaker)
02-06 19:10:29.362 738 29261 D amplifier_oneplus5: amp_enable_output_devices
02-06 19:10:29.362 738 29261 D audio_route: Apply path: speaker
02-06 19:10:29.367 738 29261 D audio_hw_primary: audio_is_true_native_stream_active:napb: (0) (compress-offload-playback)id (3) sr 44100 bw (16) device speaker
02-06 19:10:29.367 738 29261 D audio_hw_primary: audio_is_true_native_stream_active:napb:native stream detected
02-06 19:10:29.367 738 29261 D audio_hw_utils: audio_extn_utils_send_app_type_cfg: usecase->out_snd_device speaker
02-06 19:10:29.367 738 29261 D msm8974_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0)
02-06 19:10:29.368 738 29261 D audio_hw_utils: audio_extn_btsco_get_sample_rate:Not a BT SCO device, need not update sampling rate
02-06 19:10:29.368 738 29261 I audio_hw_utils: send_app_type_cfg_for_device PLAYBACK app_type 69936, acdb_dev_id 14, sample_rate 48000, snd_device_be_idx 2
02-06 19:10:29.368 738 29261 D msm8974_platform: platform_split_snd_device: snd_device(2) num devices(1) new_snd_devices(0)
02-06 19:10:29.368 738 29261 D ACDB-LOADER: ACDB -> send_audio_cal, acdb_id = 14, path = 0, app id = 0x11130, sample rate = 48000
02-06 19:10:29.368 738 29261 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 5
02-06 19:10:29.369 738 29261 D ACDB-LOADER: ACDB -> AUDIO_SET_VOL_CAL cal type = 12
02-06 19:10:29.369 738 29261 D ACDB-LOADER: ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456
02-06 19:10:29.369 738 29261 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 14, Topology Id 1025e
02-06 19:10:29.369 738 29261 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 14 path = 0
02-06 19:10:29.369 738 29261 D audio_hw_primary: enable_audio_route: apply mixer and update path: compress-offload-playback quat_i2s
02-06 19:10:29.369 738 29261 D audio_route: Apply path: compress-offload-playback quat_i2s
02-06 19:10:29.370 738 29261 D amplifier_oneplus5: amp_set_input_devices
02-06 19:10:29.370 738 29261 D amplifier_oneplus5: amp_set_output_devices: devices=2
02-06 19:10:29.370 738 29261 D audio_hw_primary: select_devices: done
02-06 19:10:29.370 738 29261 D msm8974_platform: platform_set_channel_map mixer_ctl_name:Playback Channel Map8
02-06 19:10:29.371 738 29261 D msm8974_platform: platform_set_channel_map: set mapping(1 2 0 0 0 0 0 0) for channel:2
02-06 19:10:29.380 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 9. Total Items:25
02-06 19:10:29.383 846 867 V /vendor/bin/adsprpcd: vendor/qcom/proprietary/adsprpc/src/apps_std_imp.c:196: fopen success: /dsp/VorbisDecoderModule.so.1
02-06 19:10:29.407 738 29261 D audio_hw_utils: audio_extn_utils_compress_set_render_mode:: render mode 0
02-06 19:10:29.407 738 29261 D audio_hw_utils: audio_extn_utils_compress_set_clk_rec_mode:: clk recovery is only supported in STC render mode
02-06 19:10:29.407 738 29262 D offload_visualizer: thread enter
02-06 19:10:29.407 738 29261 E audio_hw_extn: audio_extn_perf_lock_release: Perf lock release error
02-06 19:10:29.408 738 29261 D audio_hw_primary: start_output_stream: exit
02-06 19:10:29.408 738 29261 D amplifier_oneplus5: amp_output_stream_start
02-06 19:10:29.408 738 29261 D audio_hw_primary: copl(0xecf0b000):send new gapless metadata
02-06 19:10:29.419 2417 2417 W StaticLayout: maxLineHeight should not be -1. maxLines:1 lineCount:1
02-06 19:10:29.420 2417 2417 I chatty : uid=10020(com.android.systemui) identical 1 line
02-06 19:10:29.420 2417 2417 W StaticLayout: maxLineHeight should not be -1. maxLines:1 lineCount:1
02-06 19:10:29.467 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:30.010 871 29254 I chatty : uid=1013(media) AudioTrack identical 33 lines
02-06 19:10:30.041 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:30.063 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 9. Total Items:25
02-06 19:10:30.063 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:31.044 871 29254 I chatty : uid=1013(media) AudioTrack identical 57 lines
02-06 19:10:31.060 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:31.072 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 9. Total Items:25
02-06 19:10:31.075 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.043 871 29254 I chatty : uid=1013(media) AudioTrack identical 57 lines
02-06 19:10:32.057 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.071 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 9. Total Items:25
02-06 19:10:32.072 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.372 871 29254 I chatty : uid=1013(media) AudioTrack identical 17 lines
02-06 19:10:32.390 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.398 845 966 I ThermalEngine: Monitor : quiet_therm = 22, msm_therm = 23, ufs_therm = -40, battery_therm = 208,current_now = -389000
02-06 19:10:32.409 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.757 871 29254 I chatty : uid=1013(media) AudioTrack identical 22 lines
02-06 19:10:32.771 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.784 871 29258 D NuPlayerDecoderPassThrough: return aggregated buffer and save err(=-1011) for later
02-06 19:10:32.784 871 29254 D AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded)
02-06 19:10:32.798 738 29255 D audio_hw_primary: copl(0xecf0b000):calling compress_partial_drain
02-06 19:10:32.820 738 29255 D audio_hw_primary: copl(0xecf0b000):out of compress_partial_drain
02-06 19:10:32.821 738 29261 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: Using Fix DSP delay
02-06 19:10:32.821 738 29261 D audio_hw_utils: audio_extn_utils_compress_get_dsp_latency:: delay in ms is 50
02-06 19:10:32.821 871 29251 D NuPlayerDriver: notifyListener_l(0xedfc4310), (2, 0, 0, -1), loop setting(0, 0)
02-06 19:10:32.823 851 851 D AudioPolicyManagerCustom: setForceUse() usage 1, config 10, mPhoneState 0
02-06 19:10:32.824 28970 28996 I DownloadServiceImpl: Ending position 34 of 200000
02-06 19:10:32.824 28970 28996 I DownloadServiceImpl: STARTED -> COMPLETED (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:32.825 28970 28996 I CancellableTask: Cancelling BufferTask (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:32.825 28970 28996 I DownloadServiceImpl: COMPLETED -> IDLE (DownloadFile (Entry(id=663, parent=, isDirectory=false, title=Everybody Needs Somebody to Love, album=The Very Best of the Blues Brothers, albumId=45, artist=Blues Brothers, artistId=39, track=1, year=1995, genre=, contentType=audio/ogg, suffix=ogg, transcodedContentType=, transcodedSuffix=, coverArt=al-45, size=4078005, songCount=null, duration=200, bitRate=-1, path=, isVideo=false, starred=false, discNumber=1, type=music, created=Wed Feb 05 18:49:18 GMT 2020, closeness=0, bookmarkPosition=0)))
02-06 19:10:32.829 871 871 D NuPlayerDriver: reset(0xedfc4310) at state 6
02-06 19:10:32.829 871 871 D NuPlayerDriver: notifyListener_l(0xedfc4310), (8, 0, 0, -1), loop setting(0, 0)
02-06 19:10:32.830 851 1054 W AudioFlinger: moveEffectChain_l() effect chain for session 0 not on source thread 0xe5ecd000
02-06 19:10:32.832 738 1383 D audio_hw_primary: out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: routing=0
02-06 19:10:32.833 738 1383 D amplifier_oneplus5: amp_out_set_parameters
02-06 19:10:32.833 738 1383 D amplifier_oneplus5: set_anc_parameter
02-06 19:10:32.833 738 1383 D audio_hw_primary: out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: closing=true
02-06 19:10:32.833 738 1383 D amplifier_oneplus5: amp_out_set_parameters
02-06 19:10:32.833 738 1383 D amplifier_oneplus5: set_anc_parameter
02-06 19:10:32.834 738 1383 D audio_hw_primary: out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: exiting=1
02-06 19:10:32.834 738 1383 D amplifier_oneplus5: amp_out_set_parameters
02-06 19:10:32.834 738 1383 D amplifier_oneplus5: set_anc_parameter
02-06 19:10:32.835 871 29251 D NuPlayerDriver: notifyResetComplete(0xedfc4310)
02-06 19:10:32.835 871 871 D NuPlayerDriver: reset(0xedfc4310) at state 0
02-06 19:10:32.835 738 29255 D audio_hw_primary: copl(0xecf0b000):calling compress_drain
02-06 19:10:32.835 738 738 D audio_hw_primary: out_standby: enter: stream (0xecf0b000) usecase(3: compress-offload-playback)
02-06 19:10:32.835 738 29255 D audio_hw_primary: copl(0xecf0b000):calling compress_drain
02-06 19:10:32.835 738 738 D amplifier_oneplus5: amp_output_stream_standby
02-06 19:10:32.835 738 738 D audio_hw_primary: copl(0xecf0b000):standby
02-06 19:10:32.836 28970 28996 V MediaPlayer: resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
02-06 19:10:32.836 28970 28996 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
02-06 19:10:32.839 28970 28996 I DownloadServiceImpl: In updateRemoteControl, playerState: IDLE [0]
02-06 19:10:32.844 28970 29230 I DownloadServiceLifecycleSupport: Serialized currentPlayingIndex: -1, currentPlayingPosition: 0
02-06 19:10:32.848 28970 29230 I FileUtil: Serialized object to /data/user/0/org.moire.ultrasonic/cache/downloadstate.ser
02-06 19:10:32.849 28970 28970 D PullToRefresh: onSizeChanged. W: 1080, H: 1680
02-06 19:10:32.849 28970 28970 D PullToRefresh: Setting Padding. L: 0, T: -1008, R: 0, B: 0
02-06 19:10:32.851 2417 2417 D FGSExtender: setShouldManageLifetime true
02-06 19:10:32.856 3578 3578 D NotificationRepository: updateNotification() called with: list = [14]
02-06 19:10:32.865 3578 3578 D NotificationRepository: updateNotification() called with: list = [14]
02-06 19:10:32.875 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 10. Total Items:25
02-06 19:10:32.893 738 29262 D offload_visualizer: thread exit
02-06 19:10:32.894 738 738 D audio_hw_primary: disable_audio_route: reset and update mixer path: compress-offload-playback quat_i2s
02-06 19:10:32.895 738 738 D hardware_info: hw_info_append_hw_type : device_name = speaker
02-06 19:10:32.895 738 738 D audio_hw_primary: disable_snd_device: snd_device(2: speaker)
02-06 19:10:32.895 738 738 D msm8974_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0)
02-06 19:10:32.898 738 738 D amplifier_oneplus5: amp_enable_output_devices
02-06 19:10:32.899 738 738 D audio_hw_primary: out_standby: exit
02-06 19:10:32.899 738 1383 D audio_hw_primary: adev_close_output_stream: enter:stream_handle(compress-offload-playback)
02-06 19:10:32.900 738 1383 D audio_hw_primary: out_standby: enter: stream (0xecf0b000) usecase(3: compress-offload-playback)
02-06 19:10:32.900 738 1383 D audio_hw_primary: out_standby: exit
02-06 19:10:33.068 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 10. Total Items:25
02-06 19:10:34.091 28970 28970 D PullToRefresh: First Visible: 0. Visible Count: 10. Total Items:25
02-06 19:10:34.359 2417 2417 D FGSExtender: setShouldManageLifetime false
Solutions
Workaround
Don't know any.