[Bug] iOS - Music Track Immediately Skipped

In the latest beta (and for a few versions of the app) when I directly select a track from either search results or an album view the UI will show the track as playing for a second but immediately skips to the next track. This happens so often I’ve given up using the app for music for now and just use iTunes Match.

Please let me know if you need more info to fix it and how I can get it for you.

Here are some logs that appear to show the track playback being completed with sub-second response time. I’ve removed anything that looked GUIDish just in case it was a token.

2019/04/08 19:35:09.374 (159 MB) (1056632) πŸ”§ PMKTrebleAudioPlayer.mm:93 | TREBLE: Media: We're going to play immediately, and we have something fully cached of bitrate 256.
2019/04/08 19:35:09.374 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opening stream 190593 (aac, 256 Kbps)
2019/04/08 19:35:09.374 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Opened cache file with 8323511 bytes (of 8323511 total) at '/private/var/mobile/Containers/Data/Application/619088BB-71A5-4446-AAD5-FB85B7CBA7C9/Library/Caches/Plexamp/Media/REDACTED'.
2019/04/08 19:35:09.376 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190593 active to 0, 0 active remaining.
2019/04/08 19:35:09.376 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
2019/04/08 19:35:09.376 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Stream 190593 completed caching.
2019/04/08 19:35:09.376 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190593 active to 0, 0 active remaining.
2019/04/08 19:35:09.376 (159 MB) (1056632) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
2019/04/08 19:35:09.439 (159 MB) (1050364) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Resuming with fade.
2019/04/08 19:35:09.439 (159 MB) (1050364) βž– PMKPlaybackEngine.m:338 | <PMKAudioPlaybackEngine: 0x283905b30> started playing { <PMKPlayQueueMetadata:0x119ffa600 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190593 from app context <PMKMetadataAppContext: 0x280476ac0 metadataType=album>
2019/04/08 19:35:09.462 (159 MB) (1056621) πŸ”§ PMKMetricsEngine.m:325 | Generated identifiable metrics event playback:itemstart {
    audioCodec = aac;
    audioDecision = "<null>";
    auto = 0;
    bitrate = 256;
    connectionType = local;
    container = mp4;
    context = "music.recent.artist.3";
    duration = 240;
    identifier = "com.plexapp.plugins.library";
    latency = 774;
    mode = music;
    offset = 0;
    page = album;
    player = TREBLE;
    serverType = owned;
    sessionIdentifier = "Redacted";
    type = track;
}
2019/04/08 19:35:09.492 (159 MB) (1050364) βž– PMKAudioPlaybackEngine.m:622 | Finished playing 190593, now playing 190594 and currentObject is { <PMKPlayQueueMetadata:0x119ffa600 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190593
2019/04/08 19:35:09.492 (159 MB) (1050364) βž– PMKPlaybackEngine.m:407 | <PMKAudioPlaybackEngine: 0x283905b30> stopped playing { <PMKPlayQueueMetadata:0x119ffa600 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190593 with reason 'completed' from app context <PMKMetadataAppContext: 0x280476ac0 metadataType=album>

It gets even more interesting when Verbose is turned on. Here you can see me search for β€œwavin” and tap the track to start playing item 190648. Assuming the numbers in parentheses are threads it looks like 1066836 is getting ready to play the track while 1067207 starts caching ahead. Since these tracks are found in the cache is 1067207 just finishing too fast and now there’s a race condition with 1066836?

Sorry to read too much in to this but it’s been driving me nuts for months and if this cant be fixed I don’t need this app on my phone anymore.

2019/04/08 20:01:55.837 (138 MB) (1067110) πŸ”§ PMKNetworkTask.m:232 | Jon’s iPhone 7+ (REDACTED): GET http://127.0.0.1:32500/media/providers/1/hubs/search?includeCollections=1&includeExternalMedia=1&limit=10&query=Wavin
        X-Plex-Supported-Commands           :  abort,changeQuality
        User-Agent                          :  PlexMobile/5.13 (iPhone; iOS 12.2; Scale/3.00)
        X-Plex-Device-Screen-Density        :  3
        X-Plex-Device                       :  iPhone
        X-Plex-Platform                     :  iOS
        X-Plex-Sync-Version                 :  2
        X-Plex-Token                        :  REDACTED
        X-Plex-Username                     :  jonno_ct
        X-Plex-Provides                     :  client,controller,sync-target,server
        X-Plex-Version                      :  5.13
        X-Plex-Client-Platform              :  iOS
        X-Plex-Model                        :  9,2
        X-Plex-Product                      :  Plex for iOS
        X-Plex-Online                       :  1
        X-Plex-Client-Identifier            :  REDACTED
        Accept-Language                     :  en-US;q=1
        X-Plex-Device-Screen-Resolution     :  1242x2208
        X-Plex-Device-Vendor                :  Apple
        X-Plex-Platform-Version             :  12.2
        Accept                              :  text/xml,application/xml
        X-Plex-Http-Pipeline                :  infinite
        includeCollections                  :  1 [param]
        query                               :  Wavin [param]
        X-Plex-Device-Name                  :  Jon’s iPhone 7+ [param]
        includeExternalMedia                :  1 [param]
        limit                               :  10 [param]
2019/04/08 20:01:55.881 (138 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/media/providers/1/hubs/search) with 16 children for "Wavin" on Podcasts
2019/04/08 20:01:55.882 (138 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for Podcasts on Jon’s iPhone 7+
2019/04/08 20:01:56.021 (138 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/hubs/search) with 1 children for "Wavin" on News
2019/04/08 20:01:56.021 (138 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for News on Cloud
2019/04/08 20:01:56.107 (138 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/hubs/search) with 2 children for "Wavin" on Web Shows
2019/04/08 20:01:56.109 (138 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for Web Shows on Cloud
2019/04/08 20:01:56.156 (139 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/hubs/search) with 16 children for "Wavin" on jukebox
2019/04/08 20:01:56.157 (139 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for jukebox on jukebox
2019/04/08 20:01:56.189 (140 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/media/providers/1/hubs/search) with 2 children for "Wavin" on Podcasts
2019/04/08 20:01:56.192 (140 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for Podcasts on Jon’s iPhone 7+
2019/04/08 20:01:56.516 (141 MB) (1066836) βž– PMKContentSourceSearchProvider.m:332 | Processing container (/tv.plex.providers.epg.onconnect:2/hubs/search) with 16 children for "Wavin" on Live TV & DVR
2019/04/08 20:01:56.527 (141 MB) (1066836) βž– PMKSearchController.m:463 | Processed search results for Live TV & DVR on jukebox
2019/04/08 20:01:58.185 (141 MB) (1066917) πŸ”§ PMKNetworkTask.m:232 | jukebox (REDACTED): GET /library/metadata/39109
        X-Plex-Supported-Commands           :  abort,changeQuality
        User-Agent                          :  PlexMobile/5.13 (iPhone; iOS 12.2; Scale/3.00)
        X-Plex-Device-Screen-Density        :  3
        X-Plex-Device                       :  iPhone
        X-Plex-Platform                     :  iOS
        X-Plex-Sync-Version                 :  2
        X-Plex-Token                        :  REDACTED
        X-Plex-Username                     :  jonno_ct
        X-Plex-Provides                     :  client,controller,sync-target,server
        X-Plex-Version                      :  5.13
        X-Plex-Client-Platform              :  iOS
        X-Plex-Model                        :  9,2
        X-Plex-Product                      :  Plex for iOS
        X-Plex-Client-Identifier            :  REDACTED
        Accept-Language                     :  en-US;q=1
        X-Plex-Device-Screen-Resolution     :  1242x2208
        X-Plex-Device-Vendor                :  Apple
        X-Plex-Platform-Version             :  12.2
        Accept                              :  text/xml,application/xml
        X-Plex-Http-Pipeline                :  infinite
        X-Plex-Device-Name                  :  Jon’s iPhone 7+ [param]
2019/04/08 20:01:58.230 (141 MB) (1066836) πŸ”§ PMKPlayer.m:261 | Creating PQ from request with content source <PMKServerContentSource: 0x282d44580>, server <PMKServer: 0x283171560>[ standard server jukebox (hasPresence) - REDACTED ][ 1.15.3.876-ad6e39743 ]
2019/04/08 20:01:58.231 (141 MB) (1066836) βž– PMKPlayerBackgroundTaskManager.m:268 | Starting playback background task...
2019/04/08 20:01:58.244 (141 MB) (1066836) πŸ”§ PMKPlayQueueServerStrategy.m:481 | Updating PQ from content source <PMKServerContentSource: 0x282d44580>, server <PMKServer: 0x283171560>[ standard server jukebox (hasPresence) - REDACTED ][ 1.15.3.876-ad6e39743 ]
2019/04/08 20:01:58.257 (141 MB) (1067025) πŸ”§ PMKNetworkTask.m:232 | jukebox (REDACTED): POST /playQueues?continuous=0&includeExternalMedia=1&includeLoudnessRamps=1&key=/library/metadata/39109&recursive=0&shuffle=0&type=audio&uri=server://REDACTED/com.plexapp.plugins.library/library/metadata/39100
        X-Plex-Supported-Commands           :  abort,changeQuality
        User-Agent                          :  PlexMobile/5.13 (iPhone; iOS 12.2; Scale/3.00)
        X-Plex-Device-Screen-Density        :  3
        X-Plex-Device                       :  iPhone
        X-Plex-Platform                     :  iOS
        X-Plex-Sync-Version                 :  2
        X-Plex-Token                        : REDCATED
        X-Plex-Username                     :  jonno_ct
        X-Plex-Provides                     :  client,controller,sync-target,server
        X-Plex-Version                      :  5.13
        X-Plex-Client-Platform              :  iOS
        X-Plex-Model                        :  9,2
        X-Plex-Product                      :  Plex for iOS
        X-Plex-Client-Identifier            :  REDACTED
        Accept-Language                     :  en-US;q=1
        X-Plex-Device-Screen-Resolution     :  1242x2208
        X-Plex-Device-Vendor                :  Apple
        X-Plex-Platform-Version             :  12.2
        Accept                              :  text/xml,application/xml
        X-Plex-Http-Pipeline                :  infinite
        continuous                          :  0 [param]
        recursive                           :  0 [param]
        includeLoudnessRamps                :  1 [param]
        shuffle                             :  0 [param]
        key                                 :  /library/metadata/39109 [param]
        type                                :  audio [param]
        includeExternalMedia                :  1 [param]
        uri                                 :  server://REDACTED/com.plexapp.plugins.library/library/metadata/39100 [param]
        X-Plex-Device-Name                  :  Jon’s iPhone 7+ [param]
2019/04/08 20:01:58.677 (141 MB) (1066836) πŸ“ PMKServerTimelineGenerator.m:229 | Sending server timeline for player <PMKLocalPlayer: 0x283458b00>[ embedded player Jon’s iPhone 7+ - REDACTED] (audio) about metadata { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648: {
    bufferedTime = 0;
    duration = 205007;
    key = "/library/metadata/39109";
    location = lan;
    playQueueItemID = 190648;
    playbackTime = 0;
    ratingKey = 39109;
    state = paused;
    time = 5632;
}
2019/04/08 20:01:58.684 (141 MB) (1066836) βž– PMKPlayQueueServerStrategy.m:603 | New play queue: 
 <PMKPlayQueue: 0x280c76fd0>

ID: 2962
version: 1
selected item: 190648
last added: N/A
type: Audio

Up Next (0 objects):
(Empty)

Play Queue (11 objects):
- Cmon Talk (190640)
- Crazy In Love/Deja Vu (190641)
- Stop This Train (190642)
- Proud Mary (190643)
- Thinkin Bout You (190644)
- End Of Time (190645)
- Paradise (190646)
- No Woman, No Cry (190647)
> Wavin' Flag (190648)
- I Can't Make You Love Me (190649)
- Atlas/Garands (190650)

2019/04/08 20:01:58.686 (141 MB) (1066836) βž– PMKLastPlayingStateController.m:282 | Start tracking audio playback in player <PMKLocalPlayer: 0x283458b00>[ embedded player Jon’s iPhone 7+ - REDACTED ]
2019/04/08 20:01:58.706 (142 MB) (1067109) πŸ”§ PMKNetworkTask.m:232 | jukebox (REDACTED): GET /:/timeline?bufferedTime=0&duration=205007&key=/library/metadata/39109&location=lan&playQueueItemID=190648&playbackTime=0&ratingKey=39109&state=paused&time=5632
        X-Plex-Supported-Commands           :  abort,changeQuality
        User-Agent                          :  PlexMobile/5.13 (iPhone; iOS 12.2; Scale/3.00)
        X-Plex-Device-Screen-Density        :  3
        X-Plex-Device                       :  iPhone
        X-Plex-Platform                     :  iOS
        X-Plex-Sync-Version                 :  2
        X-Plex-Token                        :  REDACTED
        X-Plex-Username                     :  jonno_ct
        X-Plex-Provides                     :  client,controller,sync-target,server
        X-Plex-Version                      :  5.13
        X-Plex-Client-Platform              :  iOS
        X-Plex-Model                        :  9,2
        X-Plex-Product                      :  Plex for iOS
        X-Plex-Client-Identifier            :  REDACTED
        Accept-Language                     :  en-US;q=1
        X-Plex-Device-Screen-Resolution     :  1242x2208
        X-Plex-Device-Vendor                :  Apple
        X-Plex-Platform-Version             :  12.2
        Accept                              :  text/xml,application/xml
        X-Plex-Http-Pipeline                :  infinite
        location                            :  lan [param]
        state                               :  paused [param]
        time                                :  5632 [param]
        X-Plex-Device-Name                  :  Jon’s iPhone 7+ [param]
        duration                            :  205007 [param]
        playQueueItemID                     :  190648 [param]
        key                                 :  /library/metadata/39109 [param]
        bufferedTime                        :  0 [param]
        playbackTime                        :  0 [param]
        ratingKey                           :  39109 [param]
2019/04/08 20:01:58.714 (142 MB) (1066836) πŸ”§ PMKTrebleAudioPlayer.mm:93 | TREBLE: BASS: Starting up.
2019/04/08 20:01:58.715 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Device 1: Default - enabled default - sample rates:
2019/04/08 20:01:58.715 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Device 2: Voice - enabled - sample rates:
2019/04/08 20:01:58.715 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Version 2.4.14.0
2019/04/08 20:01:58.715 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Mixer version 2.4.9.0
2019/04/08 20:01:58.715 (142 MB) (1066836) πŸ”§ PMKTrebleAudioPlayer.mm:93 | TREBLE: BASS: Going to try to open device with sample rate of 44100.
2019/04/08 20:01:58.792 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Device opened and sample rate is 48000, latency is 0ms (minimum buffer: 0ms).
2019/04/08 20:01:58.805 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Stop.
2019/04/08 20:01:58.808 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Set stream tempo to 0.0
2019/04/08 20:01:58.808 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Set stream tempo to 0.0
2019/04/08 20:01:58.820 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Stop.
2019/04/08 20:01:58.841 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:453 | Preparing next audio item (190649 - I Can't Make You Love Me) for playback
2019/04/08 20:01:58.845 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190649 active to 1, 1 active remaining.
2019/04/08 20:01:58.845 (142 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Queueing track (now 1 total) with identifier 190649, gain nan dB, overlap duration 0 ms, start offset 0 ms (paused: 1).
2019/04/08 20:01:58.845 (142 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opening stream 190649 with max bitrate of 0.
2019/04/08 20:01:58.849 (142 MB) (1067045) πŸ”§ PMKNetworkTask.m:232 | Jon’s iPhone 7+ (REDACTED): GET http://127.0.0.1:32500/library/metadata/268474565
        Accept                              :  text/xml,application/xml
        X-Plex-Client-Platform              :  iOS
        X-Plex-Model                        :  9,2
        X-Plex-Device-Vendor                :  Apple
        X-Plex-Platform                     :  iOS
        X-Plex-Supported-Commands           :  abort,changeQuality
        X-Plex-Product                      :  Plex for iOS
        X-Plex-Client-Identifier            :  REDACTED
        X-Plex-Device-Screen-Density        :  3
        Accept-Language                     :  en-US;q=1
        X-Plex-Device                       :  iPhone
        X-Plex-Http-Pipeline                :  infinite
        X-Plex-Version                      :  5.13
        User-Agent                          :  PlexMobile/5.13 (iPhone; iOS 12.2; Scale/3.00)
        X-Plex-Device-Screen-Resolution     :  1242x2208
        X-Plex-Username                     :  jonno_ct
        X-Plex-Provides                     :  client,controller,sync-target,server
        X-Plex-Platform-Version             :  12.2
        X-Plex-Sync-Version                 :  2
        X-Plex-Device-Name                  :  Jon’s iPhone 7+ [param]
2019/04/08 20:01:58.857 (142 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Opened cache file with 6715145 bytes (of 6715145 total) at '/private/var/mobile/Containers/Data/Application/619088BB-71A5-4446-AAD5-FB85B7CBA7C9/Library/Caches/Plexamp/Media/REDACTED.
2019/04/08 20:01:58.857 (143 MB) (1067200) πŸ”§ PMKTrebleAudioPlayer.mm:93 | TREBLE: Media: We're going to play immediately, and we have something fully cached of bitrate 256.
2019/04/08 20:01:58.857 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opening stream 190649 (aac, 256 Kbps)
2019/04/08 20:01:58.858 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Opened cache file with 6715145 bytes (of 6715145 total) at '/private/var/mobile/Containers/Data/Application/619088BB-71A5-4446-AAD5-FB85B7CBA7C9/Library/Caches/Plexamp/Media/REDACTED.
2019/04/08 20:01:58.858 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190649 active to 0, 0 active remaining.
2019/04/08 20:01:58.858 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
2019/04/08 20:01:58.858 (144 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Stream 190649 completed caching.
2019/04/08 20:01:58.858 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190649 active to 0, 0 active remaining.
2019/04/08 20:01:58.858 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
2019/04/08 20:01:58.860 (143 MB) (1067110) πŸ“ PMKEventSourceManager.m:204 | Event received (playing): {"PlaySessionStateNotification":{"sessionKey":"14","guid":"","ratingKey":"39109","url":"","key":"/library/metadata/39109","viewOffset":5632,"playQueueItemID":190648,"state":"paused"}}
2019/04/08 20:01:58.891 (143 MB) (1067202) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Max size was 512 MB, deleted 0 files in cache, 515.6 MB remain in use.
2019/04/08 20:01:58.898 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Media was fully cached, just reading from disk.
2019/04/08 20:01:58.898 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opened stream 190649 (channel 0x8000000b) in 40 ms.
2019/04/08 20:01:58.898 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Register stream 190649 (channel 0x8000000b), 1 total.
2019/04/08 20:01:58.898 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Stream 190649 is 32 KB/sec (stream reported 32 KB/sec).
2019/04/08 20:01:58.898 (143 MB) (1067200) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Added stream 190649 (channel: 0x8000000b) to the mixer (paused: 1).
2019/04/08 20:01:58.910 (143 MB) (1066836) πŸ”§ PMKPlaybackSession.m:205 | Preferring synced copy of { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648 at /library/metadata/268474565
2019/04/08 20:01:58.913 (143 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190648 active to 1, 1 active remaining.
2019/04/08 20:01:58.913 (143 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Queueing track (now 2 total) with identifier 190648, gain nan dB, overlap duration 0 ms, start offset 0 ms (paused: 1).
2019/04/08 20:01:58.913 (143 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Pre-caching next stream 190648
2019/04/08 20:01:58.915 (143 MB) (1066836) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Resume.
2019/04/08 20:01:58.915 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opening stream 190648 with max bitrate of 0.
2019/04/08 20:01:58.915 (143 MB) (1066836) βž– PMKPlaybackEngine.m:338 | <PMKAudioPlaybackEngine: 0x283c5f390> started playing { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648 from app context <PMKStaticAppContext: 0x280316e40 identifier=search>
2019/04/08 20:01:58.915 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Opened cache file with 8323511 bytes (of 8323511 total) at '/private/var/mobile/Containers/Data/Application/619088BB-71A5-4446-AAD5-FB85B7CBA7C9/Library/Caches/Plexamp/Media/REDACTED'.
2019/04/08 20:01:58.915 (143 MB) (1067207) πŸ”§ PMKTrebleAudioPlayer.mm:93 | TREBLE: Media: We're going to play immediately, and we have something fully cached of bitrate 256.
2019/04/08 20:01:58.915 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: BASS: Opening stream 190648 (aac, 256 Kbps)
2019/04/08 20:01:58.917 (143 MB) (1066836) πŸ“ PMExternalScreenManager.m:68 | Detected playback, starting video on external screen...
2019/04/08 20:01:58.918 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Cache: Opened cache file with 8323511 bytes (of 8323511 total) at '/private/var/mobile/Containers/Data/Application/619088BB-71A5-4446-AAD5-FB85B7CBA7C9/Library/Caches/Plexamp/Media/REDACTED'.
2019/04/08 20:01:58.918 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Set item 190648 active to 0, 0 active remaining.
2019/04/08 20:01:58.918 (143 MB) (1067207) βž– PMKTrebleAudioPlayer.mm:88 | TREBLE: Play Queue: Looking for 2 items ahead (total of 0) to pre-cache with network type of -1
2019/04/08 20:01:58.920 (143 MB) (1066836) βž– PMKAudioPlaybackEngine.m:622 | Finished playing 190648, now playing 190649 and currentObject is { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648
2019/04/08 20:01:58.920 (143 MB) (1066836) βž– PMKPlaybackEngine.m:407 | <PMKAudioPlaybackEngine: 0x283c5f390> stopped playing { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648 with reason 'completed' from app context <PMKStaticAppContext: 0x280316e40 identifier=search>
2019/04/08 20:01:58.921 (143 MB) (1067111) πŸ”§ PMKMetricsEngine.m:325 | Generated identifiable metrics event playback:itemstart {
    audioCodec = aac;
    audioDecision = "<null>";
    auto = 0;
    bitrate = 256;
    connectionType = local;
    container = mp4;
    context = search;
    duration = 240;
    identifier = "com.plexapp.plugins.library";
    latency = 760;
    mode = music;
    offset = 0;
    page = discover;
    player = TREBLE;
    serverType = owned;
    sessionIdentifier = "REDACTED";
    type = track;
}
2019/04/08 20:01:58.922 (143 MB) (1066836) πŸ“ PMKServerTimelineGenerator.m:229 | Sending server timeline for player <PMKLocalPlayer: 0x283458b00>[ embedded player Jon’s iPhone 7+ - REDACTED ] (audio) about metadata { <PMKPlayQueueMetadata:0x1342287c0 key:/library/metadata/39109 title:Wavin' Flag type:10 isDirectory:0> }, playQueueItemID:190648: {
    bufferedTime = 100;
    continuing = 1;
    duration = 255000;
    key = "/library/metadata/39109";
    location = lan;
    playQueueItemID = 190648;
    playbackTime = 0;
    ratingKey = 39109;
    state = stopped;
    time = 0;
}
2019/04/08 20:01:58.923 (143 MB) (1066836) πŸ“ PMExternalScreenManager.m:73 | Playback stopped, destroying playback window...
2019/04/08 20:01:58.926 (143 MB) (1066836) πŸ“ PMKServerTimelineGenerator.m:229 | Sending server timeline for player <PMKLocalPlayer: 0x283458b00>[ embedded player Jon’s iPhone 7+ - REDACTED ] (audio) about metadata { <PMKPlayQueueMetadata:0x13423a800 key:/library/metadata/39110 title:I Can't Make You Love Me type:10 isDirectory:0> }, playQueueItemID:190649: {
    bufferedTime = 100;
    duration = 205007;
    key = "/library/metadata/39110";
    location = lan;
    playQueueItemID = 190649;
    playbackTime = 0;
    ratingKey = 39110;
    state = paused;
    time = 0;
}

1 Like

I am having a similar issue with my wife’s iPhone and mine. It is happening to the same tracks and at the same time. The album starts fine for a few songs, then on the same song, it skips immediately after about 2 seconds. Playback is fine on web app on another device and this doesn’t occur. My server is up to date, iOS apps up to date, iOS overall is current version… totally perplexed as well.

Relatedly, the iPhone app has often been stopping playback altogether after a few songs. The timer continues to run on the home screen but music has stopped. Opening the app, it shows it stuck where it stopped. I close the app and restart and this fixes it for a while.

Not enjoying these bugs.

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