search

TeamNewPipe / NewPipe

A libre lightweight streaming front-end for Android.
https://newpipe.net
GNU General Public License v3.0
31.73k stars 3.09k forks source link

"Recovering from player error" when continuing playback in background #6491

Open jmbreuer opened 3 years ago

jmbreuer commented 3 years ago

Checklist

Steps to reproduce the bug

  1. have NewPipe set up to continue (audio) playback in background when switching out of the app
  2. have a (long-ish) video playing fullscreen (landscape): https://youtu.be/LjhgMGVW-Kc my phone is set to portrait mode irrespective of orientation sensor - I have a hunch this / the UI rotation during task switch plays into the bug
  3. press the Home button, causing a switch to the full screen portrait launcher

Actual behaviour

Sometimes, playback will cease and a dialog saying "Recovering from player error" comes up. OKing that dialog also does not resume playback. Again, sometimes it's possible to continue playback by unpausing in the play controls of the NewPipe interactive notification.

Expected behavior

Audio playback should continue (fairly seamlessly, at roughly the correct position, without manual interventions).

Screenshots/Screen recordings

Reproducing this to get screenshots / logs I get different behavior from the original problem yet again...

Now, when I switch out of NewPipe, I'll only get a very brief toast at the bottom of the screen saying "Unrecoverable player error occurred". In this case, there is no NewPipe media control notification afterwards. I can switch back into NewPipe and resume playback there.

Logs

I captured a logcat trace when the (latter) error (which I can readily reproduce right now) occurs:

06-15 17:16:59.930  3400  3416 I ActivityManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.teslacoilsw.launcher/.NovaLauncher (has extras)} from uid 1000 on display 0
06-15 17:16:59.933 10827 10827 D Bugsnag : Received NDK message dbxyzptlk.Z2.F0$a@85dea78
06-15 17:17:00.188  3400  3416 W LocalDisplayAdapter: Unable to find color mode 0, ignoring request.
06-15 17:17:00.196  3400  3416 I ActivityManager: Config changes=480 {1.15 262mcc2mnc [en_DE,en_US,de_DE] ldltr sw457dp w457dp h788dp 504dpi nrml long port finger -keyb/v/h -nav/h s.42}
06-15 17:17:00.251  3400  3459 W art     : Long monitor contention with owner android.ui (3416) at int com.android.server.am.ActivityStarter.startActivityMayWait(android.app.IApplicationThread, int, java.lang.String, android.content.Intent, java.lang.String, android.service.voice.IVoiceInteractionSession, com.android.internal.app.IVoiceInteractor, android.os.IBinder, java.lang.String, int, int, android.app.ProfilerInfo, android.app.IActivityManager$WaitResult, android.content.res.Configuration, android.os.Bundle, boolean, int, android.app.IActivityContainer, com.android.server.am.TaskRecord)(ActivityStarter.java:790) waiters=3 in int com.android.server.am.PendingIntentRecord.sendInner(int, android.content.Intent, java.lang.String, android.content.IIntentReceiver, java.lang.String, android.os.IBinder, java.lang.String, int, int, int, android.os.Bundle, android.app.IActivityContainer) for 249ms
06-15 17:17:00.252  3400  7451 W art     : Long monitor contention with owner android.ui (3416) at int com.android.server.am.ActivityStarter.startActivityMayWait(android.app.IApplicationThread, int, java.lang.String, android.content.Intent, java.lang.String, android.service.voice.IVoiceInteractionSession, com.android.internal.app.IVoiceInteractor, android.os.IBinder, java.lang.String, int, int, android.app.ProfilerInfo, android.app.IActivityManager$WaitResult, android.content.res.Configuration, android.os.Bundle, boolean, int, android.app.IActivityContainer, com.android.server.am.TaskRecord)(ActivityStarter.java:790) waiters=0 in android.content.pm.UserInfo com.android.server.am.UserController.getCurrentUser() for 316ms
06-15 17:17:00.252  3400  3415 W art     : Long monitor contention with owner android.ui (3416) at int com.android.server.am.ActivityStarter.startActivityMayWait(android.app.IApplicationThread, int, java.lang.String, android.content.Intent, java.lang.String, android.service.voice.IVoiceInteractionSession, com.android.internal.app.IVoiceInteractor, android.os.IBinder, java.lang.String, int, int, android.app.ProfilerInfo, android.app.IActivityManager$WaitResult, android.content.res.Configuration, android.os.Bundle, boolean, int, android.app.IActivityContainer, com.android.server.am.TaskRecord)(ActivityStarter.java:790) waiters=1 in void com.android.server.am.ActivityManagerService$MainHandler.handleMessage(android.os.Message) for 305ms
06-15 17:17:00.328 10827 10827 D Bugsnag : Received NDK message dbxyzptlk.Z2.F0$a@e3d9551
06-15 17:17:00.329 10827 10827 D Bugsnag : Received NDK message dbxyzptlk.Z2.F0$o@15d71b6
06-15 17:17:00.330 10827 10827 D Bugsnag : Received NDK message dbxyzptlk.Z2.F0$a@6330eb7
06-15 17:17:00.337 24537 11454 E GmsConnectionFactory: UID for network attribution might be misset for tag 805319424 [CONTEXT service_id=39 ]
06-15 17:17:00.396  3400  3473 I InputReader: Reconfiguring input devices.  changes=0x00000004
06-15 17:17:00.396  3400  3473 I InputReader: Device reconfigured: id=17, name='sec_touchscreen', size 1440x2560, orientation 0, mode 1, display id 0
06-15 17:17:00.397  3400  3473 I InputReader: Reconfiguring input devices.  changes=0x00000004
06-15 17:17:00.538  3048  3959 E BufferQueueProducer: [SurfaceView - org.schabi.newpipe/org.schabi.newpipe.MainActivity] queueBuffer: BufferQueue has no connected producer
06-15 17:17:00.543  5716 11472 E Surface : queueBuffer: error queuing buffer to SurfaceTexture, -19
06-15 17:17:00.543  5716 11472 E ACodec  : queueBuffer failed in onOutputBufferDrained: -19
06-15 17:17:00.543  5716 11472 E ACodec  : signalError(omxError 0x80001001, internalError -19)
06-15 17:17:00.544  5716 11471 E MediaCodec: Codec reported err 0xffffffed, actionCode 0, while in state 6
06-15 17:17:00.548  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026db40
06-15 17:17:00.548  5716 11472 E ACodec  : queueBuffer failed in onOutputBufferDrained: -22
06-15 17:17:00.548  5716 11472 E ACodec  : signalError(omxError 0x80001001, internalError -22)
06-15 17:17:00.551  5716 11471 E MediaCodec: Codec reported err 0xffffffea, actionCode 0, while in state 0
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal: Playback error
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:   com.google.android.exoplayer2.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, null, -1, null, [1280, 544, 25.0], [-1, -1]), format_supported=YES
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:542)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at android.os.Handler.dispatchMessage(Handler.java:98)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at android.os.Looper.loop(Looper.java:154)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at android.os.HandlerThread.run(HandlerThread.java:61)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:   Caused by: com.google.android.exoplayer2.video.MediaCodecVideoDecoderException: Decoder failed: OMX.Exynos.AVC.Decoder
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1377)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:877)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:892)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:467)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       ... 3 more
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:   Caused by: java.lang.IllegalStateException
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at android.media.MediaCodec.native_dequeueOutputBuffer(Native Method)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at android.media.MediaCodec.dequeueOutputBuffer(MediaCodec.java:2570)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.SynchronousMediaCodecAdapter.dequeueOutputBufferIndex(SynchronousMediaCodecAdapter.java:58)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.drainOutputBuffer(MediaCodecRenderer.java:1784)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:862)
06-15 17:17:00.563  5716 11337 E ExoPlayerImplInternal:       ... 5 more
06-15 17:17:00.565  4440  4809 D mali_winsys: EGLint new_window_surface(egl_winsys_display*, void*, EGLSurface, EGLConfig, egl_winsys_surface**, egl_color_buffer_format*, EGLBoolean) returns 0x3000,  [1440x2560]-format:1
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal: Disable failed.
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:   java.lang.IllegalStateException
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at android.media.MediaCodec.native_flush(Native Method)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at android.media.MediaCodec.flush(MediaCodec.java:2054)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.SynchronousMediaCodecAdapter.flush(SynchronousMediaCodecAdapter.java:81)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.flushOrReleaseCodec(MediaCodecRenderer.java:919)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.onDisabled(MediaCodecRenderer.java:778)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.video.MediaCodecVideoRenderer.onDisabled(MediaCodecVideoRenderer.java:454)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.BaseRenderer.disable(BaseRenderer.java:175)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.disableRenderer(ExoPlayerImplInternal.java:1507)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.resetInternal(ExoPlayerImplInternal.java:1254)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.stopInternal(ExoPlayerImplInternal.java:1218)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at com.google.android.exoplayer2.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:546)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at android.os.Handler.dispatchMessage(Handler.java:98)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at android.os.Looper.loop(Looper.java:154)
06-15 17:17:00.565  5716 11337 E ExoPlayerImplInternal:       at android.os.HandlerThread.run(HandlerThread.java:61)
06-15 17:17:00.573  3067 11476 W libexynosv4l2: failed to ioctl: VIDIOC_DQBUF (22 - Invalid argument)
06-15 17:17:00.576  3067 11475 W libexynosv4l2: failed to ioctl: VIDIOC_DQBUF (22 - Invalid argument)
06-15 17:17:00.584  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026fc60
06-15 17:17:00.584  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 25 to native window
06-15 17:17:00.585  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f60270160
06-15 17:17:00.586  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 23 to native window
06-15 17:17:00.587  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f60270200
06-15 17:17:00.587  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 22 to native window
06-15 17:17:00.589  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f60270340
06-15 17:17:00.589  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 20 to native window
06-15 17:17:00.592  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026e5e0
06-15 17:17:00.592  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 18 to native window
06-15 17:17:00.593  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026e680
06-15 17:17:00.593  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 17 to native window
06-15 17:17:00.594  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026f300
06-15 17:17:00.594  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 16 to native window
06-15 17:17:00.595  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026d1e0
06-15 17:17:00.595  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 15 to native window
06-15 17:17:00.595  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026da00
06-15 17:17:00.596  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 14 to native window
06-15 17:17:00.596  5716 11472 E Surface : getSlotFromBufferLocked: unknown buffer: 0x7f6026db40
06-15 17:17:00.596  5716 11472 W ACodec  : [OMX.Exynos.AVC.Decoder] can not return buffer 13 to native window
06-15 17:17:00.662 30539 11140 W NetworkScheduler: Error inserting service_kind=0 source=16 tag=Measurement.PackageMeasurementTaskService.UPLOAD_TASK_TAG target_package=com.google.android.gms requires_charging=0 source_version=211816000 required_network_type=0 flex_time=1809000 persistence_level=1 target_class=com.google.android.gms.measurement.PackageMeasurementTaskService runtime=1623770220655 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} preferred_network_type=1 required_idleness_state=0 preferred_charging_state=1 period=3620000 last_runtime=0 task_type=0 job_id=-1 user_id=0, error message: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067) [CONTEXT service_id=218 ]
06-15 17:17:00.698  3400  3412 W LocalDisplayAdapter: Unable to find color mode 0, ignoring request.
06-15 17:17:00.731  5716  5716 W System.err: com.google.android.exoplayer2.ExoPlaybackException: MediaCodecVideoRenderer error, index=0, format=Format(1, null, null, video/avc, null, -1, null, [1280, 544, 25.0], [-1, -1]), format_supported=YES
06-15 17:17:00.731  5716  5716 W System.err:    at com.google.android.exoplayer2.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:542)
06-15 17:17:00.731  5716  5716 W System.err:    at android.os.Handler.dispatchMessage(Handler.java:98)
06-15 17:17:00.731  5716  5716 W System.err:    at android.os.Looper.loop(Looper.java:154)
06-15 17:17:00.731  5716  5716 W System.err:    at android.os.HandlerThread.run(HandlerThread.java:61)
06-15 17:17:00.731  5716  5716 W System.err: Caused by: com.google.android.exoplayer2.video.MediaCodecVideoDecoderException: Decoder failed: OMX.Exynos.AVC.Decoder
06-15 17:17:00.731  5716  5716 W System.err:    at com.google.android.exoplayer2.video.MediaCodecVideoRenderer.createDecoderException(MediaCodecVideoRenderer.java:1377)
06-15 17:17:00.731  5716  5716 W System.err:    at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:877)
06-15 17:17:00.731  5716  5716 W System.err:    at com.google.android.exoplayer2.ExoPlayerImplInternal.doSomeWork(ExoPlayerImplInternal.java:892)
06-15 17:17:00.731  5716  5716 W System.err:    at com.google.android.exoplayer2.ExoPlayerImplInternal.handleMessage(ExoPlayerImplInternal.java:467)
06-15 17:17:00.731  5716  5716 W System.err:    ... 3 more
06-15 17:17:00.732  5716  5716 W System.err: Caused by: java.lang.IllegalStateException
06-15 17:17:00.732  5716  5716 W System.err:    at android.media.MediaCodec.native_dequeueOutputBuffer(Native Method)
06-15 17:17:00.732  5716  5716 W System.err:    at android.media.MediaCodec.dequeueOutputBuffer(MediaCodec.java:2570)
06-15 17:17:00.732  5716  5716 W System.err:    at com.google.android.exoplayer2.mediacodec.SynchronousMediaCodecAdapter.dequeueOutputBufferIndex(SynchronousMediaCodecAdapter.java:58)
06-15 17:17:00.732  5716  5716 W System.err:    at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.drainOutputBuffer(MediaCodecRenderer.java:1784)
06-15 17:17:00.732  5716  5716 W System.err:    at com.google.android.exoplayer2.mediacodec.MediaCodecRenderer.render(MediaCodecRenderer.java:862)
06-15 17:17:00.732  5716  5716 W System.err:    ... 5 more
06-15 17:17:00.760  3400  3422 W LocalDisplayAdapter: Unable to find color mode 0, ignoring request.
06-15 17:17:00.770  5716  5716 I ExoPlayerImpl: Release 4e51f0 [ExoPlayerLib/2.12.3] [herolte, SM-G930F, samsung, 25] [goog.exo.core, goog.exo.mediasession]
06-15 17:17:00.773  3400  3869 I MediaFocusControl:  AudioFocus  abandonAudioFocus() from uid/pid 10338/5716 clientId=android.media.AudioManager@ff9467forg.schabi.newpipe.player.helper.AudioReactor@bbfc8d9
06-15 17:17:00.775  3533  4061 V Avrcp   : MediaController playback changed: PlaybackState {state=0, position=0, buffered position=0, speed=0.0, updated=164172507, actions=0, custom actions=[], active item id=-1, error=null}
06-15 17:17:00.776  3533  4061 V Avrcp   : updatePlayPauseState, state: PlaybackState {state=0, position=0, buffered position=0, speed=0.0, updated=164172507, actions=0, custom actions=[], active item id=-1, error=null} device: null
06-15 17:17:00.776  3533  4061 V Avrcp   : Device: no name: 
06-15 17:17:00.776  3533  4061 I Avrcp   : updatePlayStatusForDevice: device: null
06-15 17:17:00.776  3533  4061 D Avrcp   : Exit onPlaybackStateChanged()
06-15 17:17:00.778  3533  4061 V Avrcp   : MediaController metadata changed
06-15 17:17:00.778  3533  4061 V Avrcp   : MediaAttributes Changed to [MediaAttributes:  -  by  (1/0) ]
06-15 17:17:00.779  3533  4061 V Avrcp   : updatePlayPauseState, state: PlaybackState {state=0, position=0, buffered position=0, speed=0.0, updated=164172507, actions=0, custom actions=[], active item id=-1, error=null} device: null
06-15 17:17:00.779  3533  4061 V Avrcp   : Device: no name: 
06-15 17:17:00.779  3533  4061 I Avrcp   : updatePlayStatusForDevice: device: null
06-15 17:17:00.779  3533  4061 D Avrcp   : Exit onMetadataChanged()
06-15 17:17:00.781  3533  4061 V Avrcp   : Active sessions changed, 2 sessions
06-15 17:17:00.782  3533  4061 V Avrcp   : Updating media controller to android.media.session.MediaController@9ce8053
06-15 17:17:00.793  5716  5716 W IInputConnectionWrapper: getSelectedText on inactive InputConnection
06-15 17:17:00.793  3533  4061 V Avrcp   : MediaAttributes Changed to [MediaAttributes: Catonmarz - The Binding of Isaac: !newcomper Best part of this DLC is the new characters that can turn into actual battleship with hordes of cannons. -  by  (1/0) ]
06-15 17:17:00.800  3533  4061 V Avrcp   : updatePlayPauseState, state: PlaybackState {state=2, position=27619000, buffered position=0, speed=1.0, updated=158940433, actions=3893, custom actions=[], active item id=0, error=null} device: null
06-15 17:17:00.801  3533  4061 V Avrcp   : Device: no name: 
06-15 17:17:00.801  3533  4061 I Avrcp   : updatePlayStatusForDevice: device: null
06-15 17:17:00.801  3533  4061 V Avrcp   : Focus gained for player: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.801  3533  4061 D Avrcp   : Exit updateCurrentMediaController()
06-15 17:17:00.801  3533  4061 D Avrcp   : Exit onActiveSessionsChanged()
06-15 17:17:00.802  3533  4061 V Avrcp   : Active sessions changed, 2 sessions
06-15 17:17:00.802  3533  4061 V Avrcp   : Updating media controller to android.media.session.MediaController@b381d90
06-15 17:17:00.814  3533  4061 V Avrcp   : Metadata updated but no change!
06-15 17:17:00.816  3533  4061 V Avrcp   : updatePlayPauseState, state: PlaybackState {state=2, position=27619000, buffered position=0, speed=1.0, updated=158940433, actions=3893, custom actions=[], active item id=0, error=null} device: null
06-15 17:17:00.816  3533  4061 V Avrcp   : Device: no name: 
06-15 17:17:00.816  3533  4061 I Avrcp   : updatePlayStatusForDevice: device: null
06-15 17:17:00.816  3533  4061 V Avrcp   : Focus gained for player: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.817  3533  4061 D Avrcp   : Exit updateCurrentMediaController()
06-15 17:17:00.817  3533  4061 D Avrcp   : Exit onActiveSessionsChanged()
06-15 17:17:00.817  3533  4061 V Avrcp   : MSG_UPDATE_RCC_CHANGE
06-15 17:17:00.817  3533  4061 V Avrcp   : processRCCStateChange: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.817  3533  4061 V Avrcp   : MSG_UPDATE_RCC_CHANGE
06-15 17:17:00.817  3533  4061 V Avrcp   : processRCCStateChange: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.817  3533  4061 V Avrcp   : updateAddressedMediaPlayer
06-15 17:17:00.817  3533  4061 V Avrcp   : current Player: 0
06-15 17:17:00.817  3533  4061 V Avrcp   : Requested Player: 0
06-15 17:17:00.817  3533  4061 V Avrcp   : updateAddressedMediaPlayer
06-15 17:17:00.817  3533  4061 V Avrcp   : current Player: 0
06-15 17:17:00.817  3533  4061 V Avrcp   : Requested Player: 0
06-15 17:17:00.823  3565  3710 D mali_winsys: EGLint new_window_surface(egl_winsys_display*, void*, EGLSurface, EGLConfig, egl_winsys_surface**, egl_color_buffer_format*, EGLBoolean) returns 0x3000,  [1440x76]-format:1
06-15 17:17:00.834  5716  5716 W MessageQueue: Handler (android.os.Handler) {d8718fd} sending message to a Handler on a dead thread
06-15 17:17:00.834  5716  5716 W MessageQueue: java.lang.IllegalStateException: Handler (android.os.Handler) {d8718fd} sending message to a Handler on a dead thread
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.MessageQueue.enqueueMessage(MessageQueue.java:543)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Handler.enqueueMessage(Handler.java:643)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Handler.sendMessageAtTime(Handler.java:612)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Handler.sendMessageDelayed(Handler.java:582)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Handler.sendMessage(Handler.java:519)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Message.sendToTarget(Message.java:416)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.google.android.exoplayer2.ExoPlayerImplInternal.stop(ExoPlayerImplInternal.java:315)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.google.android.exoplayer2.ExoPlayerImpl.stop(ExoPlayerImpl.java:700)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.google.android.exoplayer2.SimpleExoPlayer.stop(SimpleExoPlayer.java:1698)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.google.android.exoplayer2.BasePlayer.stop(BasePlayer.java:134)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at org.schabi.newpipe.player.Player.destroyPlayer(Player.java:772)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at org.schabi.newpipe.player.Player.destroy(Player.java:801)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at org.schabi.newpipe.player.MainPlayer.onDestroy(MainPlayer.java:193)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.app.ActivityThread.handleStopService(ActivityThread.java:3381)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.app.ActivityThread.-wrap27(ActivityThread.java)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1606)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Handler.dispatchMessage(Handler.java:102)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.os.Looper.loop(Looper.java:154)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at android.app.ActivityThread.main(ActivityThread.java:6186)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at java.lang.reflect.Method.invoke(Native Method)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889)
06-15 17:17:00.834  5716  5716 W MessageQueue:  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779)
06-15 17:17:00.835  5716  5716 I ExoPlayerImpl: Release 4e51f0 [ExoPlayerLib/2.12.3] [herolte, SM-G930F, samsung, 25] [goog.exo.core, goog.exo.mediasession]
06-15 17:17:00.835  3400 17763 I MediaFocusControl:  AudioFocus  abandonAudioFocus() from uid/pid 10338/5716 clientId=android.media.AudioManager@ff9467forg.schabi.newpipe.player.helper.AudioReactor@bbfc8d9
06-15 17:17:00.836  3400 17769 D MediaSessionService: Unknown session changed playback state. Ignoring.
06-15 17:17:00.841  3533  4061 V Avrcp   : Active sessions changed, 2 sessions
06-15 17:17:00.842  3533  4061 V Avrcp   : Updating media controller to android.media.session.MediaController@d46a689
06-15 17:17:00.842  5716  5716 W Player  : Broadcast receiver already unregistered: Receiver not registered: org.schabi.newpipe.player.Player$3@2c1348e
06-15 17:17:00.845  5716  5716 W IInputConnectionWrapper: getTextAfterCursor on inactive InputConnection
06-15 17:17:00.850  3565  3565 W View    : requestLayout() improperly called by com.android.internal.widget.NotificationExpandButton{d87df5a V.ED..C.. ......ID 822,51-866,98 #10203b6 android:id/expand_button} during layout: running second layout pass
06-15 17:17:00.850  3565  3565 W View    : requestLayout() improperly called by com.android.internal.widget.NotificationExpandButton{fee974d V.ED..C.. ......ID 822,51-866,98 #10203b6 android:id/expand_button} during layout: running second layout pass
06-15 17:17:00.850  3565  3565 W View    : requestLayout() improperly called by com.android.internal.widget.NotificationExpandButton{633a8b9 V.ED..C.. ......ID 822,51-866,98 #10203b6 android:id/expand_button} during layout: running second layout pass
06-15 17:17:00.861  3533  4061 V Avrcp   : Metadata updated but no change!
06-15 17:17:00.864  3533  4061 V Avrcp   : updatePlayPauseState, state: PlaybackState {state=2, position=27619000, buffered position=0, speed=1.0, updated=158940433, actions=3893, custom actions=[], active item id=0, error=null} device: null
06-15 17:17:00.864  3533  4061 V Avrcp   : Device: no name: 
06-15 17:17:00.864  3533  4061 I Avrcp   : updatePlayStatusForDevice: device: null
06-15 17:17:00.866  3533  4061 V Avrcp   : Focus gained for player: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.866  3533  4061 D Avrcp   : Exit updateCurrentMediaController()
06-15 17:17:00.866  3533  4061 D Avrcp   : Exit onActiveSessionsChanged()
06-15 17:17:00.866  3533  4061 V Avrcp   : MSG_UPDATE_RCC_CHANGE
06-15 17:17:00.866  3533  4061 V Avrcp   : processRCCStateChange: org.leetzone.android.yatsewidgetfree
06-15 17:17:00.866  3533  4061 V Avrcp   : updateAddressedMediaPlayer
06-15 17:17:00.866  3533  4061 V Avrcp   : current Player: 0
06-15 17:17:00.866  3533  4061 V Avrcp   : Requested Player: 0
06-15 17:17:00.883  5716  5753 D mali_winsys: EGLint new_window_surface(egl_winsys_display*, void*, EGLSurface, EGLConfig, egl_winsys_surface**, egl_color_buffer_format*, EGLBoolean) returns 0x3000,  [974x148]-format:1
06-15 17:17:00.885  5716  5716 W IInputConnectionWrapper: beginBatchEdit on inactive InputConnection
06-15 17:17:00.886  5716  5716 W IInputConnectionWrapper: endBatchEdit on inactive InputConnection
06-15 17:17:00.904  5716  5716 W IInputConnectionWrapper: getTextBeforeCursor on inactive InputConnection
06-15 17:17:00.905 24123 24123 E FullInputEventModel: selectionUpdated event aborted: oa3: Could not create selection changed event (class oa3)
06-15 17:17:00.905 24123 24123 I Choreographer: Skipped 33 frames!  The application may be doing too much work on its main thread.
06-15 17:17:00.909  5716  5716 W IInputConnectionWrapper: reportFullscreenMode on inexistent InputConnection
06-15 17:17:00.918  3565  3565 D mali_winsys: EGLint new_window_surface(egl_winsys_display*, void*, EGLSurface, EGLConfig, egl_winsys_surface**, egl_color_buffer_format*, EGLBoolean) returns 0x3000,  [2560x1920]-format:2
06-15 17:17:00.992  3565  3565 D mali_winsys: EGLint new_window_surface(egl_winsys_display*, void*, EGLSurface, EGLConfig, egl_winsys_surface**, egl_color_buffer_format*, EGLBoolean) returns 0x3000,  [2160x2560]-format:2
06-15 17:17:01.047  3400  3422 I WindowManager: Screen frozen for +909ms due to Window{c1363e5 u0 com.android.systemui.ImageWallpaper}
06-15 17:17:01.245  3063  3289 W AudioFlinger::EffectModule: EffectModule 0xf54db000 destructor called with unreleased interface
06-15 17:17:01.251  3063 13766 W AudioFlinger::EffectHandle: disconnect Effect handle 0xf588a300 disconnected after thread destruction
06-15 17:17:02.752  3400  3400 W WindowManager: Attempted to remove non-existing token: android.os.Binder@fc37ca1
06-15 17:17:02.835 11487 11487 W Thread-1198: type=1400 audit(0.0:2338): avc: denied { read } for name="mem" dev="debugfs" ino=8722 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:debugfs:s0 tclass=dir permissive=0
06-15 17:17:05.060  3400  3422 I art     : Starting a blocking GC Explicit
06-15 17:17:05.272  3400  3422 I art     : Explicit concurrent mark sweep GC freed 51864(2MB) AllocSpace objects, 2(40KB) LOS objects, 28% free, 39MB/55MB, paused 1.514ms total 211.108ms

I'll try to resolve a couple of the PIDs:

PID Process
3400 Android System
5716 NewPipe
3533 Bluetooth Share

Originally I encountered the problem over wired analog earphones, reproduced right now using just the built in speaker. No Bluetooth device paired - guess the Bluetooth service likes to keep track of media playing anyway.

Device info

jmbreuer commented 3 years ago

I'm on mobile, there was no template there (I was expecting one). When I'm at a proper machine, I'll edit accordingly. Also, the duplicate is not the main issue of this report - switching to background playback erroring out is.

jmbreuer commented 3 years ago

Updated the bug report according to template (apparently, that did not come up when I used "New Issue" at the top of a bug search, as I did). Added some info.

ShareASmile commented 2 weeks ago

@jmbreuer Does this issue still occur on the current latest version 0.27.2?