Android: audio playback on remote connection stop after few seconds

Plex Players Mobile Devices
1

Server Version#: 1.20.1.3252 (raspbian)
Player Version#: 8.6.0.20351 (android)
Home Router: AVM Fritz!box 7490 OS: 7.12

log:

09-26 07:56:24.584  i: [UserAction] Select menu item: Cerca.
09-26 07:56:24.627  i: Creating UNOSearchActivity.
09-26 07:56:24.627  i: [Activity] Resuming the application, attempting to download item and children.
09-26 07:56:24.630  i: [Boot] UNOSearchActivity took 3ms to run create behaviours.
09-26 07:56:24.635  i: [Boot] FragmentWithBehavioursDelegate took 0ms to run create behaviours.
09-26 07:56:24.662  i: Resuming UNOSearchActivity.
09-26 07:56:26.444  i: [Boot] FragmentWithBehavioursDelegate took 0ms to run create behaviours.
09-26 07:56:27.075  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/hubs/search?contentDirectoryID=5&contextual=1&includeCollections=1&limit=10&query=lo&X-Plex-Token=...DYXQ
09-26 07:56:27.795  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/hubs/search?contentDirectoryID=playlists%2C1%2C3%2C4&includeCollections=1&limit=10&query=lo&X-Plex-Token=...DYXQ
09-26 07:56:27.814  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.817  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.818  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.819  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.829  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.830  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.830  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.831  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.839  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.841  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.841  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.842  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.850  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.852  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.855  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:27.855  e: Presenter not found! Type: section, Subtype: unknown
09-26 07:56:29.692  i: Creating PreplayActivity.
09-26 07:56:29.697  i: [Boot] PreplayActivity took 1ms to run create behaviours.
09-26 07:56:29.715  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8498?asyncAugmentMetadata=1&includeConcerts=1&includeExtras=1&includePlexLinks=1&includePopularLeaves=1&includeReviews=1&includeStations=1&X-Plex-Token=...DYXQ
09-26 07:56:29.724  i: Resuming PreplayActivity.
09-26 07:56:29.926  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8498/children?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CVast%2CTopic&excludeFields=tagline%2Cfile&includeExternalMetadata=1&includeLibraryPlaylists=1&includeRecentChannels=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...DYXQ
09-26 07:56:30.059  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/hubs/metadata/8498/related?count=20&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CVast%2CTopic&excludeFields=summary%2Ctagline%2Cfile&includeAugmentations=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...DYXQ
09-26 07:56:34.665  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8499?asyncAugmentMetadata=1&X-Plex-Token=...DYXQ
09-26 07:56:34.798  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8499/children?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CVast%2CTopic&excludeFields=tagline%2Cfile&includeExternalMetadata=1&includeLibraryPlaylists=1&includeRecentChannels=1&includeRelated=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...DYXQ
09-26 07:56:35.015  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/hubs/metadata/8499/related?count=20&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CVast%2CTopic&excludeFields=summary%2Ctagline%2Cfile&includeAugmentations=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1&X-Plex-Token=...DYXQ
09-26 07:56:36.214  i: [OneApp] Entitlement 'activation' needs refreshing.
09-26 07:56:36.216  i: [IabHelper] IAB helper created.
09-26 07:56:36.217  i: [IabHelper] Starting in-app billing setup.
09-26 07:56:37.248  i: [IabHelper] Billing service connected.
09-26 07:56:37.251  i: [IabHelper] Checking for in-app billing 3 support.
09-26 07:56:37.682  i: [IabHelper] In-app billing version 3 supported for com.plexapp.android
09-26 07:56:37.693  i: [IabHelper] Subscriptions AVAILABLE.
09-26 07:56:37.693  i: [IabHelper] Starting async operation: refresh inventory
09-26 07:56:37.694  i: [IabHelper] Querying owned items, item type: inapp
09-26 07:56:37.695  i: [IabHelper] Package name: com.plexapp.android
09-26 07:56:37.695  i: [IabHelper] Calling getPurchases with continuation token: null
09-26 07:56:37.704  i: [IabHelper] Owned items response: 0
09-26 07:56:37.707  i: [IabHelper] Sku is owned: plex_for_android_activation
09-26 07:56:37.708  i: [IabHelper] Continuation token: null
09-26 07:56:37.709  i: [IabHelper] Querying SKU details.
09-26 07:56:38.520  i: [IabHelper] Got sku details: SkuDetails:{"skuDetailsToken":"AEuhp4KTsJXDOpWlZT7HLdD5pEJE8OtCXSGs6Sat0R-ORTqSXN4BPNoi0X9vHn3idYuU","productId":"plex_for_android_activation","type":"inapp","price":"5,28 €","price_amount_micros":5280000,"price_currency_code":"EUR","title":"Plex for Android Activation (Plex: Stream Free Movies, Shows, Live TV & more)","description":"Plex for Android Activation"}
09-26 07:56:38.520  i: [IabHelper] Querying owned items, item type: subs
09-26 07:56:38.520  i: [IabHelper] Package name: com.plexapp.android
09-26 07:56:38.525  i: [IabHelper] Calling getPurchases with continuation token: null
09-26 07:56:38.530  i: [IabHelper] Owned items response: 0
09-26 07:56:38.531  i: [IabHelper] Continuation token: null
09-26 07:56:38.532  i: [IabHelper] Querying SKU details.
09-26 07:56:38.801  i: [IabHelper] Ending async operation: refresh inventory
09-26 07:56:38.810  i: [Billing] Current product plex_for_android_activation is owned, so no need to check the old ones.
09-26 07:56:38.811  i: [Activation] Product query completed successfully. Result: ProductQueryResult{currentProductInfo=ProductInfo{sku=plex_for_android_activation, term=Lifetime, usdPrice=4.99, formattedPrice=5,28 €, price=5.28, currency=EUR}, productOwnershipInfo=ProductOwnershipInfo{receiptId=cgbihfefbjedjpihhagbbnjg.AO-J1OxN5dWpSHswz4aNHf8NX3uMHQ-lTP9x8nmrKORIqFyyMsLuFrtKDIElksFJro4dFaVedbbn8UrHzbkhe4nX2u76-u-i_Qqdw8bGg9sXUSirmsk4UY5SnA2_J_-LaM61JFCS5f27dlVHKM10NTGNZLQ3K3R5MQ, orderId=GPA.3374-5842-0727-13264, term=Lifetime, purchasingUser=PurchasingUser{plexUserId=11138539, plexUsername=Blackdisk}, sku=plex_for_android_activation, formattedPrice=5,28 €}}.
09-26 07:56:38.815  i: [OneApp] Adding entitlement: activation.
09-26 07:56:38.815  i: [IabHelper] Disposing.
09-26 07:56:38.815  i: [IabHelper] Unbinding from service.
09-26 07:56:38.815  i: [OneApp] Finished refreshing entitlement 'activation': it's owned
09-26 07:56:38.847  i: [OneApp] User can execute command without restrictions because he is entitled.
09-26 07:56:38.872  i: [PlayQueues] Creating delayed remote PQ.
09-26 07:56:38.916  i: [MediaBrowserAudioService] onCreate
09-26 07:56:38.918  i: [PlayerService] onCreate
09-26 07:56:38.929  i: [ConnectivityManager] Device is under a metered connection
09-26 07:56:38.937  i: [NativeLibrary] Attempting to load `c++_shared`.
09-26 07:56:38.937  i: [NativeLibrary] Attempting to load `bass`.
09-26 07:56:38.951  i: [NativeLibrary] Attempting to load `bass_ape`.
09-26 07:56:38.966  i: [NativeLibrary] Attempting to load `bass_fx`.
09-26 07:56:38.971  i: [NativeLibrary] Attempting to load `bass_mpc`.
09-26 07:56:38.974  i: [NativeLibrary] Attempting to load `bassflac`.
09-26 07:56:38.977  i: [NativeLibrary] Attempting to load `bassmix`.
09-26 07:56:38.980  i: [NativeLibrary] Attempting to load `bassopus`.
09-26 07:56:38.984  i: [NativeLibrary] Attempting to load `bassalac`.
09-26 07:56:38.987  i: [NativeLibrary] Attempting to load `bass_aac`.
09-26 07:56:38.991  i: [NativeLibrary] Attempting to load `Treble`.
09-26 07:56:39.011  i: [Player] Changing to use Treble for playback.
09-26 07:56:39.037  i: [Treble]: BASS: Device 1: Default - enabled default - sample rates:
09-26 07:56:39.038  i: [Treble]: BASS: Device 2: Default (alarm) - enabled - sample rates:
09-26 07:56:39.038  i: [Treble]: BASS: Device 3: Default (voice) - enabled - sample rates:
09-26 07:56:39.044  i: [Treble]: BASS: Core version 2.4.15.0
09-26 07:56:39.044  i: [Treble]: BASS: Mixer version 2.4.9.10
09-26 07:56:39.044  i: [Treble]: BASS: FX version 2.4.12.1
09-26 07:56:39.047  i: [Treble]: BASS: Curl version is libcurl/7.56.1 OpenSSL/1.0.2p zlib/1.2.11 libidn2/2.0.5
09-26 07:56:39.049  i: [NetworkTypeMonitor] Starting to monitor
09-26 07:56:39.052  i: [Treble]: BASS: Network switched to 1.
09-26 07:56:39.078  i: [Player][Treble] onAudioQueueSessionOptionsChanged
09-26 07:56:39.079  i: [Player][Treble] onAudioQueueSessionOptionsChanged
09-26 07:56:39.081  i: [Player][Treble] onRuntimeSessionOptionsChanged
09-26 07:56:39.082  i: [Player][Treble] onRuntimeSessionOptionsChanged
09-26 07:56:39.084  i: [MemoryOptimisationBehaviour] Reducing memory cache.
09-26 07:56:39.085  i: [Player][Treble] onRuntimeSessionOptionsChanged
09-26 07:56:39.098  i: [MediaSessionHelper] Starting media session with tag: music
09-26 07:56:39.099  i: [MediaSessionHelper] Creating media session with tag: music
09-26 07:56:39.113  i: [CurrentItemMetadataBehaviour] Fetching current item
09-26 07:56:39.115  i: [MediaSessionBehaviour] Updating metadata for music, thumb: false
09-26 07:56:39.122  i: [Player][Treble] onRuntimeSessionOptionsChanged
09-26 07:56:39.124  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8500?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1&X-Plex-Token=...DYXQ
09-26 07:56:39.125  i: [Treble]: BASS: Resume.
09-26 07:56:39.128  w: [Treble]: BASS: Can't resume, no current stream.
09-26 07:56:39.142  i: [MediaBrowserAudioService] Rebuilding...
09-26 07:56:39.152  i: [Player][Treble] Adding both current and next track, audio queue length: 0
09-26 07:56:39.154  i: [Treble]: BASS: Stop.
09-26 07:56:39.155  i: [Treble]: Play Queue: Clearing.
09-26 07:56:39.155  i: Creating PlayerActivity.
09-26 07:56:39.156  i: [Activity] Resuming the application, attempting to download item and children.
09-26 07:56:39.163  i: [Boot] PlayerActivity took 6ms to run create behaviours.
09-26 07:56:39.167  i: [PlayerActivity] Creating fragment
09-26 07:56:39.173  i: [Player][DimensionsLayout] Resizing: 100 x 100
09-26 07:56:39.178  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8500?X-Plex-Token=...DYXQ
09-26 07:56:39.179  i: [ConnectivityManager] Device is under a metered connection
09-26 07:56:39.243  i: [MenuSheetHud] Item changed (and fetched), invalidating settings...
09-26 07:56:39.338  i: [Player][Treble] onRuntimeSessionOptionsChanged
09-26 07:56:39.339  i: [Treble]: BASS: Resume.
09-26 07:56:39.339  w: [Treble]: BASS: Can't resume, no current stream.
09-26 07:56:39.350  i: Resuming PlayerActivity.
09-26 07:56:39.359  i: [SystemUIBehaviour] Showing the system user-interface.
09-26 07:56:39.360  i: [CurrentItemMetadataBehaviour] Fetched current item, notifying listeners
09-26 07:56:39.360  i: [MenuSheetHud] Item changed (and fetched), invalidating settings...
09-26 07:56:39.361  e: Error loading bitmap from URL: https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/photo/:/transcode?machineIdentifier=7fafab29a78de2de6b11c5ab86007745d0ce85fa&width=512&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F8499%2Fthumb%2F1600915610&height=512&X-Plex-Token=...DYXQ
09-26 07:56:39.361  e: An exception occurred: javax.net.ssl.SSLProtocolException: Read error: ssl=0x7b14122008: Failure in SSL library, usually a protocol error
error:1e000065:Cipher functions:OPENSSL_internal:BAD_DECRYPT (external/boringssl/src/crypto/cipher_extra/e_chacha20poly1305.c:336 0x7baf767e6b:0x00000000)
error:1000008b:SSL routines:OPENSSL_internal:DECRYPTION_FAILED_OR_BAD_RECORD_MAC (external/boringssl/src/ssl/tls_record.cc:298 0x7baf767e6b:0x00000000)
09-26 07:56:39.362  e: Stacktrace: javax.net.ssl.SSLProtocolException: Read error: ssl=0x7b14122008: Failure in SSL library, usually a protocol error
error:1e000065:Cipher functions:OPENSSL_internal:BAD_DECRYPT (external/boringssl/src/crypto/cipher_extra/e_chacha20poly1305.c:336 0x7baf767e6b:0x00000000)
error:1000008b:SSL routines:OPENSSL_internal:DECRYPTION_FAILED_OR_BAD_RECORD_MAC (external/boringssl/src/ssl/tls_record.cc:298 0x7baf767e6b:0x00000000)
	at com.android.org.conscrypt.NativeCrypto.SSL_read(Native Method)
	at com.android.org.conscrypt.NativeSsl.read(NativeSsl.java:411)
	at com.android.org.conscrypt.ConscryptFileDescriptorSocket$SSLInputStream.read(ConscryptFileDescriptorSocket.java:549)
	at f.n$b.read(SourceFile:4)
	at f.a$b.read(SourceFile:2)
	at f.p.read(SourceFile:3)
	at okhttp3.internal.http1.Http1Codec$AbstractSource.read(SourceFile:1)
	at okhttp3.internal.http1.Http1Codec$FixedLengthSource.read(SourceFile:3)
	at f.p.read(SourceFile:3)
	at okhttp3.internal.cache.CacheInterceptor$1.read(SourceFile:1)
	at f.p.read(SourceFile:3)
	at f.p.e(SourceFile:3)
	at f.p.a(SourceFile:17)
	at f.p.a(SourceFile:14)
	at com.squareup.picasso.f0.a(SourceFile:58)
	at com.squareup.picasso.c.a(SourceFile:2)
	at com.squareup.picasso.c.l(SourceFile:13)
	at com.squareup.picasso.x.e(SourceFile:8)
	at com.plexapp.plex.utilities.p7.e(SourceFile:1)
	at com.plexapp.plex.net.a5.t1(SourceFile:3)
	at com.plexapp.plex.player.n.b4.a(SourceFile:5)
	at com.plexapp.plex.player.n.q0.run(Unknown Source:4)
	at android.os.Handler.handleCallback(Handler.java:883)
	at android.os.Handler.dispatchMessage(Handler.java:100)
	at android.os.Looper.loop(Looper.java:214)
	at android.os.HandlerThread.run(HandlerThread.java:67)

09-26 07:56:39.378  i: [Player][View] Layout has been measured with a size of 1080 x 2160 (1080 x 2160).
09-26 07:56:39.435  i: [MediaSessionBehaviour] Updating metadata for music, thumb: true
09-26 07:56:39.480  i: [Player][Treble] Adding both current and next track, audio queue length: 0
09-26 07:56:39.481  i: [Treble]: BASS: Stop.
09-26 07:56:39.481  i: [Treble]: Play Queue: Clearing.
09-26 07:56:39.491  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8500?X-Plex-Token=...DYXQ
09-26 07:56:39.633  i: [Treble]: BASS: Device opened and sample rate is 48000, latency is 145ms (minimum buffer: 10ms).
09-26 07:56:39.635  i: [Treble]: BASS: Resuming audio output (started: 0)
09-26 07:56:39.657  w: [Treble]: BASS: Mixer underflowed.
09-26 07:56:39.657  i: [Treble]: Play Queue: Set item Delay--848531488 active to 1, 1 active remaining.
09-26 07:56:39.658  i: [Treble]: BASS: Queueing stream (1 total, 1 handles) with identifier Delay--848531488, gain nan dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
09-26 07:56:39.659  i: [Player][Treble] Finished queing item Delay--848531488.
09-26 07:56:39.659  i: [Player][Treble] State (transcoding: false codec:  bitrate: 0)
09-26 07:56:39.660  i: [Treble]: BASS: Opening stream Delay--848531488.
09-26 07:56:39.660  i: [EngineEventManager] onBufferingStarted (isSeeking: false)
09-26 07:56:39.660  i: [Player][Timeline] Playback started (or buffering), scheduling updates
09-26 07:56:39.662  i: [Treble]: Cache: Opened cache file with 0 bytes (of 0 total) at '/data/user/0/com.plexapp.android/cache/Treble/Library/Caches/Media/805d414f9d5a48e641f6f339a0dd811f88a47b4f876732fce6eb14b04c17dd04'.
09-26 07:56:39.663  i: [Treble]: Media: Passing on an item with bitrate 907 and codec 'flac'.
09-26 07:56:39.664  i: [Treble]: Media: Couldn't find item with supported codec and bitrate under 160, transcoding.
09-26 07:56:39.665  i: [Treble]: Cache: Opened cache file with 0 bytes (of 0 total) at '/data/user/0/com.plexapp.android/cache/Treble/Library/Caches/Media/805d414f9d5a48e641f6f339a0dd811f88a47b4f876732fce6eb14b04c17dd04'.
09-26 07:56:39.667  i: [Treble]: Cache: Max size was 512 MB, deleted 0 files in cache, 96.4 MB remain in use.
09-26 07:56:39.668  i: [Treble]: Cache: Using RAM buffer of 2048 KB for item with bitrate of 16 KB/sec.
09-26 07:56:39.668  i: [Treble]: Cache: Buffering until offset reaches 81920 (read buffer offset: 0, size: 81920).
09-26 07:56:39.670  i: [Treble]: Cache: Opened file: https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/audio/:/transcode/universal/start?directPlay=0&musicBitrate=128&path=%2Flibrary%2Fmetadata%2F8500&session=f9b15dcfc00d50b7-com-plexapp-android-Delay--848531488&X-Plex-Chunked=1&X-Plex-Client-Identifier=f9b15dcfc00d50b7-com-plexapp-android&X-Plex-Client-Profile-Extra=add-transcode-target(replace%3Dtrue%26type%3DmusicProfile%26context%3Dstreaming%26protocol%3Dhttp%26container%3Dogg%26audioCodec%3Dopus)%2Badd-limitation(scope%3DmusicCodec%26scopeName%3Dopus%26type%3DupperBound%26name%3Daudio%2Echannels%26value%3D2%26onlyTranscodes%3Dtrue%26replace%3Dtrue)&X-Plex-Device=Mi%20A2&X-Plex-Device-Name=Mi%20A2&X-Plex-Platform=Android&X-Plex-Platform-Version=10&X-Plex-Product=Plex%20for%20Android%20(Mobile)&X-Plex-Session-Identifier=f9b15dcfc00d50b7-com-plexapp-android-Delay--848531488&X-Plex-Token=...DYXQ&X-Plex-Version=8%2E6%2E0%2E20351
09-26 07:56:39.670  i: [Treble]: Cache: Kicking off buffering thread at offset 0, disk cache has 0 / 0.
09-26 07:56:40.079  i: [Treble]: Cache: Network error (-35) occurred, we might want to kick off recovery (still 0 bytes in network buffer).
09-26 07:56:40.413  i: [Treble]: Connection Tester: success for http://xxx.xx.xxx.xx:33500!
09-26 07:56:40.495  i: [Treble]: Cache: Kicking off recovered network session (http://xxx.xx.xxx.xx:33500) at offset 0 (network ahead: 0).
09-26 07:56:40.790  i: [Treble]: Cache: HTTP response code was 200.
09-26 07:56:40.793  i: [Treble]: Cache: Network error (-18) occurred, we might want to kick off recovery (still 0 bytes in network buffer).
09-26 07:56:40.794  e: [Treble]: Cache: Network error is considered fatal.
09-26 07:56:40.816  i: [Player][Timeline] Handling player error
09-26 07:56:40.816  i: [Player][Timeline] Playback stopped (error: true)
09-26 07:56:40.816  i: [Player][Timeline] Player error not known by server, reporting original player error (or a playback interrupted one)
09-26 07:56:40.816  i: [Player] Error reported: TransientError (null)
09-26 07:56:40.817  i: [Player][Timeline] Playback stopped (error: false)
09-26 07:56:40.829  i: Showing toast: Si è verificato un errore durante la riproduzione di questo elemento.
09-26 07:56:40.840  i: [EngineEventManager] onBufferingEnded
09-26 07:56:40.840  i: [EngineEventManager] onPlaybackStopped: Completed
09-26 07:56:40.840  i: [Player][Timeline] Playback stopped (error: false)
09-26 07:56:40.840  i: [PlaybackTimeBehaviour] Time: 0 IsStarted: false
09-26 07:56:40.840  i: [PlayQueueProgressBehaviour] Updating PlayQueueManager state, isPlaying: false
09-26 07:56:40.840  i: [PlayQueueProgressBehaviour] Last item in PlayQueue completed, closing player.
09-26 07:56:40.840  i: [Player][Treble] Destroying engine
09-26 07:56:40.840  i: [Treble]: BASS: Stop.
09-26 07:56:40.840  i: [Treble]: Play Queue: Clearing.
09-26 07:56:40.840  i: [Treble]: BASS: Asked to remove stream with identifier Delay--848531488 with 1 existing streams (fade: 1, kickOffNext: 0).
09-26 07:56:40.841  i: [Treble]: Play Queue: Set item Delay--848531488 active to 0, 0 active remaining.
09-26 07:56:40.841  i: [Treble]: BASS: Removed stream Delay--848531488 from position 0, there are now 0 streams.
09-26 07:56:40.841  i: [Treble]: Cache: Asked to close file.
09-26 07:56:40.895  i: [Treble]: Cache: Ending network read thread.
09-26 07:56:40.896  i: [Treble]: Cache: READ returning EOF.
09-26 07:56:40.896  i: [NetworkTypeMonitor] Stopping monitoring
09-26 07:56:40.897  e: [Treble]: BASS: Error [ret = BASS_StreamCreate((DWORD)48000, (DWORD)m_channels, BASS_SAMPLE_FLOAT | BASS_STREAM_DECODE, StreamProc, this)] - 6
09-26 07:56:40.897  e: [Treble]: BASS: Opening stream Delay--848531488 failed. Sad.
2

This is when the player fails

09-26 07:56:48.206  i: [Player][Treble] Adding both current and next track, audio queue length: 0
09-26 07:56:48.208  i: [Treble]: BASS: Stop.
09-26 07:56:48.209  i: [Treble]: Play Queue: Clearing.
09-26 07:56:48.225  i: Fetching [method:GET] https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/library/metadata/8500?X-Plex-Token=...DYXQ
09-26 07:56:48.322  i: [Treble]: BASS: Resuming audio output (started: 1)
09-26 07:56:48.323  w: [Treble]: BASS: Mixer underflowed.
09-26 07:56:48.323  w: [Treble]: BASS: Mixer underflowed.
09-26 07:56:48.324  i: [Treble]: Play Queue: Set item Delay--1442708255 active to 1, 1 active remaining.
09-26 07:56:48.324  i: [Treble]: BASS: Queueing stream (1 total, 1 handles) with identifier Delay--1442708255, gain nan dB, overlap duration 0 ms, start offset 0 ms (paused: 0).
09-26 07:56:48.325  i: [Treble]: Play Queue: Setting current item to Delay--1442708255.
09-26 07:56:48.325  i: [Player][Treble] Finished queing item Delay--1442708255.
09-26 07:56:48.325  i: [Treble]: BASS: Opening stream Delay--1442708255.
09-26 07:56:48.327  i: [Treble]: Cache: Opened cache file with 0 bytes (of 0 total) at '/data/user/0/com.plexapp.android/cache/Treble/Library/Caches/Media/805d414f9d5a48e641f6f339a0dd811f88a47b4f876732fce6eb14b04c17dd04'.
09-26 07:56:48.328  i: [Treble]: Media: Passing on an item with bitrate 907 and codec 'flac'.
09-26 07:56:48.329  i: [Treble]: Media: Couldn't find item with supported codec and bitrate under 160, transcoding.
09-26 07:56:48.329  i: [Treble]: Cache: Opened cache file with 0 bytes (of 0 total) at '/data/user/0/com.plexapp.android/cache/Treble/Library/Caches/Media/805d414f9d5a48e641f6f339a0dd811f88a47b4f876732fce6eb14b04c17dd04'.
09-26 07:56:48.330  i: [Treble]: Cache: Using RAM buffer of 2048 KB for item with bitrate of 16 KB/sec.
09-26 07:56:48.330  i: [Treble]: Cache: Buffering until offset reaches 81920 (read buffer offset: 0, size: 81920).
09-26 07:56:48.331  i: [Treble]: Cache: Opened file: https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/audio/:/transcode/universal/start?directPlay=0&musicBitrate=128&path=%2Flibrary%2Fmetadata%2F8500&session=f9b15dcfc00d50b7-com-plexapp-android-Delay--1442708255&X-Plex-Chunked=1&X-Plex-Client-Identifier=f9b15dcfc00d50b7-com-plexapp-android&X-Plex-Client-Profile-Extra=add-transcode-target(replace%3Dtrue%26type%3DmusicProfile%26context%3Dstreaming%26protocol%3Dhttp%26container%3Dogg%26audioCodec%3Dopus)%2Badd-limitation(scope%3DmusicCodec%26scopeName%3Dopus%26type%3DupperBound%26name%3Daudio%2Echannels%26value%3D2%26onlyTranscodes%3Dtrue%26replace%3Dtrue)&X-Plex-Device=Mi%20A2&X-Plex-Device-Name=Mi%20A2&X-Plex-Platform=Android&X-Plex-Platform-Version=10&X-Plex-Product=Plex%20for%20Android%20(Mobile)&X-Plex-Session-Identifier=f9b15dcfc00d50b7-com-plexapp-android-Delay--1442708255&X-Plex-Token=...DYXQ&X-Plex-Version=8%2E6%2E0%2E20351
09-26 07:56:48.332  i: [Treble]: Cache: Kicking off buffering thread at offset 0, disk cache has 0 / 0.
09-26 07:56:48.360  i: [Player][Treble] State (transcoding: true codec: opus bitrate: 128)
09-26 07:56:48.360  i: [EngineEventManager] onBufferingStarted (isSeeking: false)
09-26 07:56:48.361  i: [Player][Timeline] Playback started (or buffering), scheduling updates
09-26 07:56:48.523  e: Error loading bitmap from URL: https://xxx-xx-xxx-xx.9872e9d0c8ee453bbeb622f6d5d473c2.plex.direct:33500/photo/:/transcode?machineIdentifier=7fafab29a78de2de6b11c5ab86007745d0ce85fa&width=512&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F8499%2Fthumb%2F1600915610&height=512&X-Plex-Token=...DYXQ
09-26 07:56:48.525  e: An exception occurred: javax.net.ssl.SSLProtocolException: Read error: ssl=0x7b4481a508: Failure in SSL library, usually a protocol error
error:1e000065:Cipher functions:OPENSSL_internal:BAD_DECRYPT (external/boringssl/src/crypto/cipher_extra/e_chacha20poly1305.c:336 0x7baf767e6b:0x00000000)
error:1000008b:SSL routines:OPENSSL_internal:DECRYPTION_FAILED_OR_BAD_RECORD_MAC (external/boringssl/src/ssl/tls_record.cc:298 0x7baf767e6b:0x00000000)
09-26 07:56:48.526  e: Stacktrace: javax.net.ssl.SSLProtocolException: Read error: ssl=0x7b4481a508: Failure in SSL library, usually a protocol error
error:1e000065:Cipher functions:OPENSSL_internal:BAD_DECRYPT (external/boringssl/src/crypto/cipher_extra/e_chacha20poly1305.c:336 0x7baf767e6b:0x00000000)
error:1000008b:SSL routines:OPENSSL_internal:DECRYPTION_FAILED_OR_BAD_RECORD_MAC (external/boringssl/src/ssl/tls_record.cc:298 0x7baf767e6b:0x00000000)
	at com.android.org.conscrypt.NativeCrypto.SSL_read(Native Method)
	at com.android.org.conscrypt.NativeSsl.read(NativeSsl.java:411)
	at com.android.org.conscrypt.ConscryptFileDescriptorSocket$SSLInputStream.read(ConscryptFileDescriptorSocket.java:549)
	at f.n$b.read(SourceFile:4)
	at f.a$b.read(SourceFile:2)
	at f.p.read(SourceFile:3)
	at okhttp3.internal.http1.Http1Codec$AbstractSource.read(SourceFile:1)
	at okhttp3.internal.http1.Http1Codec$FixedLengthSource.read(SourceFile:3)
	at f.p.read(SourceFile:3)
	at okhttp3.internal.cache.CacheInterceptor$1.read(SourceFile:1)
	at f.p.read(SourceFile:3)
	at f.p.e(SourceFile:3)
	at f.p.a(SourceFile:17)
	at f.p.a(SourceFile:14)
	at com.squareup.picasso.f0.a(SourceFile:58)
	at com.squareup.picasso.c.a(SourceFile:2)
	at com.squareup.picasso.c.l(SourceFile:13)
	at com.squareup.picasso.x.e(SourceFile:8)
	at com.plexapp.plex.utilities.p7.e(SourceFile:1)
	at com.plexapp.plex.net.a5.t1(SourceFile:3)
	at com.plexapp.plex.player.n.w3.a(SourceFile:3)
	at com.plexapp.plex.player.n.p0.run(Unknown Source:6)
	at android.os.Handler.handleCallback(Handler.java:883)
	at android.os.Handler.dispatchMessage(Handler.java:100)
	at android.os.Looper.loop(Looper.java:214)
	at android.os.HandlerThread.run(HandlerThread.java:67)

it seems that there are errors with SSL.

Any ideas?
Maybe the Fritz!Box is trying to stop the remote connection (for security purposes)?

3

I’ve tried to disable secure connection under network settings but now plex for android can’t connect at all?

log:

09-28 08:46:20.461  e: [PlexServerWebSocket] (ID: 224101542 | Server: raspberrypi) Connection failed.
09-28 08:46:20.463  e: [ServerWebSocket] Websocket error
09-28 08:46:20.463  e: An exception occurred: java.net.SocketTimeoutException: failed to connect to /192.168.178.28 (port 32400) from /**[remoteIP]** (port 46639) after 5000ms
09-28 08:46:20.463  e: An exception occurred: java.net.SocketTimeoutException: failed to connect to /192.168.178.28 (port 32400) from /**[remoteIP]** (port 46637) after 5000ms
09-28 08:46:20.467  e: Stacktrace: java.net.SocketTimeoutException: failed to connect to /192.168.178.28 (port 32400) from /**[remoteIP]** (port 46639) after 5000ms
	at libcore.io.IoBridge.connectErrno(IoBridge.java:191)
	at libcore.io.IoBridge.connect(IoBridge.java:135)
	at java.net.PlainSocketImpl.socketConnect(PlainSocketImpl.java:142)
	at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:390)
	at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:230)
	at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:212)
	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:436)
	at java.net.Socket.connect(Socket.java:621)
	at okhttp3.internal.platform.AndroidPlatform.connectSocket(SourceFile:1)
	at okhttp3.internal.connection.RealConnection.connectSocket(SourceFile:9)
	at okhttp3.internal.connection.RealConnection.connect(SourceFile:13)
	at okhttp3.internal.connection.StreamAllocation.findConnection(SourceFile:36)
	at okhttp3.internal.connection.StreamAllocation.findHealthyConnection(SourceFile:1)
	at okhttp3.internal.connection.StreamAllocation.newStream(SourceFile:6)
	at okhttp3.internal.connection.ConnectInterceptor.intercept(SourceFile:5)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:10)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:1)
	at okhttp3.internal.cache.CacheInterceptor.intercept(SourceFile:22)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:10)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:1)
	at okhttp3.internal.http.BridgeInterceptor.intercept(SourceFile:22)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:10)
	at okhttp3.internal.http.RetryAndFollowUpInterceptor.intercept(SourceFile:9)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:10)
	at okhttp3.internal.http.RealInterceptorChain.proceed(SourceFile:1)
	at okhttp3.RealCall.getResponseWithInterceptorChain(SourceFile:13)
	at okhttp3.RealCall$AsyncCall.execute(SourceFile:1)
	at okhttp3.internal.NamedRunnable.run(SourceFile:3)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
	at java.lang.Thread.run(Thread.java:919)

it seems it want to connect from remote IP using the server local IP address (and fails)?

Any help?

4

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.