Donate to e Foundation | Murena handsets with /e/OS | Own a part of Murena! Learn more

Skip to content

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.