croneter / PlexKodiConnect

Plex integration in Kodi done right
GNU General Public License v2.0
1.17k stars 77 forks source link

PKC seems to kill Squeezeplugin playback #816

Closed joswart closed 5 years ago

joswart commented 5 years ago

I am using Squeezebox for Kodi from Marcel Veldt's repository. I can start streams, but after around (not exactly) 3 minutes the stream breaks.

After that I get like half a second of the stream every 3 minutes or so. In other words it remains silent and suddenly Squeezebox starts playing and is silenced again.

Looking at the kodi debug log, I have a feeling PKC kills the stream, because it can't find a plex id. See the end of the log below.

The log is the complete dump from the "play half a second" event, described above.

2019-04-05 13:30:20.725 T:140028516710144 DEBUG: Previous line repeats 37 times. 2019-04-05 13:30:20.725 T:140028516710144 DEBUG: Thread FileCache 140028516710144 terminating 2019-04-05 13:30:21.408 T:140028449568512 DEBUG: PLEX.subscribers: PKC playqueue is still initializing - skip update 2019-04-05 13:30:22.670 T:140029556946688 INFO: PAPlayer::ProcessStream - Stream Finished 2019-04-05 13:30:22.670 T:140029556946688 DEBUG: OnQueueNextItem: CApplication::OnQueueNextItem 2019-04-05 13:30:22.684 T:140028516710144 DEBUG: Thread JobWorker start, auto delete: true 2019-04-05 13:30:22.684 T:140028516710144 DEBUG: CFileCache::Open - opening <track/radio> using cache 2019-04-05 13:30:22.685 T:140028516710144 DEBUG: CurlFile::Open(0x7f5b644f2da0) http://127.0.0.1:51100/track/radio 2019-04-05 13:30:22.690 T:140028415997696 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:22] "GET /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:22.690 T:140028415997696 DEBUG: . 2019-04-05 13:30:22.690 T:140029510792960 DEBUG: Thread FileCache start, auto delete: false 2019-04-05 13:30:22.691 T:140028516710144 DEBUG: Open - not spdif or dts file, falling back 2019-04-05 13:30:22.691 T:140029510792960 ERROR: CFileCache::Process - Error 0 seeking. Seek returned -1 2019-04-05 13:30:22.691 T:140029510792960 DEBUG: Thread FileCache 140029510792960 terminating 2019-04-05 13:30:22.691 T:140028516710144 ERROR: Init: Error creating demuxer 2019-04-05 13:30:22.692 T:140028516710144 DEBUG: CFileCache::Open - opening <track/radio> using cache 2019-04-05 13:30:22.692 T:140028516710144 DEBUG: CurlFile::Open(0x7f5b644f2da0) http://127.0.0.1:51100/track/radio 2019-04-05 13:30:22.695 T:140028321396480 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:22] "GET /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:22.695 T:140028321396480 DEBUG: . 2019-04-05 13:30:22.697 T:140029510792960 DEBUG: Thread FileCache start, auto delete: false 2019-04-05 13:30:22.698 T:140028516710144 DEBUG: Open - probing detected format [wav] 2019-04-05 13:30:22.698 T:140028516710144 DEBUG: Open - avformat_find_stream_info starting 2019-04-05 13:30:22.712 T:140028516710144 DEBUG: Open - av_find_stream_info finished 2019-04-05 13:30:22.712 T:140028516710144 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 2019-04-05 13:30:22.712 T:140028516710144 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder pcm_s16le 2019-04-05 13:30:22.712 T:140028516710144 INFO: CDVDAudioCodecFFmpeg::GetChannelMap - FFmpeg reported 2 channels, but the layout contains 0 ignoring 2019-04-05 13:30:22.797 T:140028516710144 INFO: AudioDecoder: File is queued 2019-04-05 13:30:22.798 T:140028516710144 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 2019-04-05 13:30:22.798 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:22.806 T:140028516710144 INFO: PAPlayer::PrepareStream - Ready 2019-04-05 13:30:22.813 T:140029556946688 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted 2019-04-05 13:30:22.813 T:140029556946688 DEBUG: OnAVStarted: CApplication::OnAVStarted 2019-04-05 13:30:22.815 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc 2019-04-05 13:30:22.815 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart 2019-04-05 13:30:22.823 T:140032393524160 DEBUG: CMusicGUIInfo::InitCurrentItem(http://127.0.0.1:51100/track/radio) 2019-04-05 13:30:22.823 T:140032393524160 DEBUG: CPlayerGUIInfo::InitCurrentItem(http://127.0.0.1:51100/track/radio) 2019-04-05 13:30:22.823 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc 2019-04-05 13:30:22.823 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged 2019-04-05 13:30:22.824 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnAVStart - data: {"item":{"artist":["Gruenstreifen"],"title":"\"Small Spaces\" von Cassia","type":"song"},"player":{"playerid":0,"speed":1}} 2019-04-05 13:30:22.824 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Info.OnChanged - data: null 2019-04-05 13:30:22.826 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 2019-04-05 13:30:22.826 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 2019-04-05 13:30:22.863 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnAVStart Data: {u'item': {u'title': u'"Small Spaces" von Cassia', u'type': u'song', u'artist': [u'Gruenstreifen']}, u'player': {u'playerid': 0, u'speed': 1}} 2019-04-05 13:30:22.863 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None 2019-04-05 13:30:22.863 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'title': u'"Small Spaces" von Cassia', u'type': u'song', u'artist': [u'Gruenstreifen']}, u'player': {u'playerid': 0, u'speed': 1}} 2019-04-05 13:30:22.864 T:140030261573376 DEBUG: PLEX.kodimonitor: Detected position 1 for {'playlistid': 0, 'id': None, 'version': None, 'type': 'audio', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': True, 'items': []} 2019-04-05 13:30:22.864 T:140030261573376 DEBUG: PLEX.kodimonitor: Position 1 not in PKC playqueue yet 2019-04-05 13:30:22.864 T:140030261573376 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue 2019-04-05 13:30:22.915 T:140028449568512 DEBUG: PLEX.subscribers: PKC playqueue is still initializing - skip update 2019-04-05 13:30:22.923 T:140032393524160 DEBUG: ------ Window Init (DialogBusy.xml) ------ 2019-04-05 13:30:22.924 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"artist":["Gruenstreifen"],"title":"\"Small Spaces\" von Cassia","type":"song"},"player":{"playerid":0,"speed":1}} 2019-04-05 13:30:22.932 T:140032393524160 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 2019-04-05 13:30:23.097 T:140031872165632 DEBUG: CActiveAE::DiscardStream - audio stream deleted 2019-04-05 13:30:23.097 T:140028516710144 DEBUG: DoWork - Saving file state for audio item plugin://plugin.audio.squeezebox/?action=command&params=playlist+index+0 2019-04-05 13:30:23.097 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:23.097 T:140029556946688 DEBUG: Previous line repeats 1 times. 2019-04-05 13:30:23.097 T:140029556946688 DEBUG: PAPlayer::ProcessStreams - Stream Freed 2019-04-05 13:30:23.099 T:140028516710144 DEBUG: DoWork - Marking audio item plugin://plugin.audio.squeezebox/?action=command&params=playlist+index+0 as listened 2019-04-05 13:30:23.293 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:23.382 T:140028239750912 NOTICE: plugin.audio.squeezebox --> other track requested by kodi player - index: 1 2019-04-05 13:30:23.867 T:140030261573376 INFO: easy_acquire - Created session to http://127.0.0.1 2019-04-05 13:30:23.870 T:140028424390400 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:23] "HEAD /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:23.870 T:140028424390400 DEBUG: . 2019-04-05 13:30:23.874 T:140030261573376 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'"Small Spaces" von Cassia', u'type': u'song', u'file': u'http://127.0.0.1:51100/track/radio', u'label': u'"Small Spaces" von Cassia'} 2019-04-05 13:30:23.876 T:140030261573376 DEBUG: PLEX.kodi_db.music: Found wrong number of path ids: [] for path http://127.0.0.1:51100/track/, abort 2019-04-05 13:30:23.877 T:140030261573376 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report 2019-04-05 13:30:24.423 T:140028449568512 DEBUG: PLEX.subscribers: PKC playqueue is still initializing - skip update 2019-04-05 13:30:24.428 T:140028239750912 NOTICE: plugin.audio.squeezebox --> other track requested by lms server 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers(http://127.0.0.1:51100/track/radio) 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer) 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer 2019-04-05 13:30:24.442 T:140032393524160 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players 2019-04-05 13:30:24.453 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnSpeedChanged from xbmc 2019-04-05 13:30:24.454 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSpeedChanged 2019-04-05 13:30:24.467 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSpeedChanged - data: {"item":{"artist":["Gruenstreifen"],"title":"\"Small Spaces\" von Cassia","type":"song"},"player":{"playerid":0,"speed":1}} 2019-04-05 13:30:24.479 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnSpeedChanged Data: {u'item': {u'title': u'"Small Spaces" von Cassia', u'type': u'song', u'artist': [u'Gruenstreifen']}, u'player': {u'playerid': 0, u'speed': 1}} 2019-04-05 13:30:24.502 T:140029510792960 DEBUG: Thread FileCache 140029510792960 terminating 2019-04-05 13:30:24.523 T:140031872165632 DEBUG: CActiveAE::DiscardStream - audio stream deleted 2019-04-05 13:30:24.523 T:140028516710144 DEBUG: DoWork - Saving file state for audio item plugin://plugin.audio.squeezebox/?action=command&params=playlist+index+0 2019-04-05 13:30:24.523 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:24.523 T:140029556946688 DEBUG: Previous line repeats 1 times. 2019-04-05 13:30:24.524 T:140029556946688 DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped 2019-04-05 13:30:24.524 T:140029556946688 DEBUG: Thread PAPlayer 140029556946688 terminating 2019-04-05 13:30:24.525 T:140028516710144 DEBUG: DoWork - Marking audio item plugin://plugin.audio.squeezebox/?action=command&params=playlist+index+0 as listened 2019-04-05 13:30:24.527 T:140032393524160 DEBUG: ------ Window Deinit (Custom_1114_AutoAS.xml) ------ 2019-04-05 13:30:24.527 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 2019-04-05 13:30:24.527 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 2019-04-05 13:30:24.529 T:140032393524160 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted 2019-04-05 13:30:24.530 T:140029556946688 DEBUG: Thread PAPlayer start, auto delete: false 2019-04-05 13:30:24.530 T:140029556946688 DEBUG: PAPlayer::Process - Playback started 2019-04-05 13:30:24.530 T:140032393524160 DEBUG: CMusicGUIInfo::InitCurrentItem(http://127.0.0.1:51100/track/radio) 2019-04-05 13:30:24.530 T:140032393524160 DEBUG: CPlayerGUIInfo::InitCurrentItem(http://127.0.0.1:51100/track/radio) 2019-04-05 13:30:24.530 T:140028516710144 DEBUG: CFileCache::Open - opening <track/radio> using cache 2019-04-05 13:30:24.530 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc 2019-04-05 13:30:24.530 T:140028516710144 DEBUG: CurlFile::Open(0x7f5b64490900) http://127.0.0.1:51100/track/radio 2019-04-05 13:30:24.530 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged 2019-04-05 13:30:24.534 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 2019-04-05 13:30:24.534 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 2019-04-05 13:30:24.535 T:140028281714432 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:24] "GET /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:24.535 T:140028281714432 DEBUG: . 2019-04-05 13:30:24.535 T:140029510792960 DEBUG: Thread FileCache start, auto delete: false 2019-04-05 13:30:24.536 T:140028516710144 DEBUG: Open - not spdif or dts file, falling back 2019-04-05 13:30:24.536 T:140029510792960 ERROR: CFileCache::Process - Error 0 seeking. Seek returned -1 2019-04-05 13:30:24.536 T:140029510792960 DEBUG: Thread FileCache 140029510792960 terminating 2019-04-05 13:30:24.536 T:140028516710144 ERROR: Init: Error creating demuxer 2019-04-05 13:30:24.536 T:140028516710144 DEBUG: CFileCache::Open - opening <track/radio> using cache 2019-04-05 13:30:24.537 T:140028516710144 DEBUG: CurlFile::Open(0x7f5b64490900) http://127.0.0.1:51100/track/radio 2019-04-05 13:30:24.540 T:140028380145408 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:24] "GET /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:24.540 T:140028380145408 DEBUG: . 2019-04-05 13:30:24.540 T:140029510792960 DEBUG: Thread FileCache start, auto delete: false 2019-04-05 13:30:24.541 T:140028516710144 DEBUG: Open - probing detected format [wav] 2019-04-05 13:30:24.541 T:140028516710144 DEBUG: Open - avformat_find_stream_info starting 2019-04-05 13:30:24.555 T:140028516710144 DEBUG: Open - av_find_stream_info finished 2019-04-05 13:30:24.555 T:140028516710144 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 2019-04-05 13:30:24.555 T:140028516710144 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder pcm_s16le 2019-04-05 13:30:24.555 T:140028516710144 INFO: CDVDAudioCodecFFmpeg::GetChannelMap - FFmpeg reported 2 channels, but the layout contains 0 ignoring 2019-04-05 13:30:24.567 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"artist":["Gruenstreifen"],"title":"\"Small Spaces\" von Cassia","type":"song"}} 2019-04-05 13:30:24.579 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnStop Data: {u'item': {u'title': u'"Small Spaces" von Cassia', u'type': u'song', u'artist': [u'Gruenstreifen']}, u'end': False} 2019-04-05 13:30:24.630 T:140032393524160 DEBUG: ------ Window Init (DialogBusy.xml) ------ 2019-04-05 13:30:24.635 T:140032393524160 DEBUG: ------ Window Init (Custom_1114_AutoAS.xml) ------ 2019-04-05 13:30:24.640 T:140030261573376 DEBUG: PLEX.kodimonitor: playback_cleanup called. Active players: set([]) 2019-04-05 13:30:24.640 T:140028516710144 INFO: AudioDecoder: File is queued 2019-04-05 13:30:24.640 T:140030261573376 NOTICE: PLEX.kodimonitor: Finished PKC playback cleanup 2019-04-05 13:30:24.641 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Info.OnChanged Data: None 2019-04-05 13:30:24.641 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnPlay Data: {u'item': {u'type': u'song', u'title': u'Gruenstreifen'}, u'player': {u'playerid': 0, u'speed': 1}} 2019-04-05 13:30:24.642 T:140030261573376 DEBUG: PLEX.kodimonitor: Detected position 0 for {'playlistid': 0, 'id': None, 'version': None, 'type': 'audio', 'selectedItemID': None, 'selectedItemOffset': None, 'shuffled': 0, 'repeat': 0, 'kodi_playlist_playback': False, 'pkc_edit': True, 'items': []} 2019-04-05 13:30:24.642 T:140028516710144 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000 2019-04-05 13:30:24.642 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:24.642 T:140030261573376 DEBUG: PLEX.kodimonitor: Position 0 not in PKC playqueue yet 2019-04-05 13:30:24.642 T:140030261573376 DEBUG: PLEX.kodimonitor: Need to initialize Plex and PKC playqueue 2019-04-05 13:30:24.650 T:140028516710144 INFO: PAPlayer::PrepareStream - Ready 2019-04-05 13:30:24.651 T:140029556946688 DEBUG: OnAVStarted: CApplication::OnAVStarted 2019-04-05 13:30:24.653 T:140031888951040 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc 2019-04-05 13:30:24.653 T:140031888951040 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart 2019-04-05 13:30:24.671 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Info.OnChanged - data: null 2019-04-05 13:30:24.671 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"title":"Gruenstreifen","type":"song"},"player":{"playerid":0,"speed":1}} 2019-04-05 13:30:24.677 T:140032393524160 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 2019-04-05 13:30:24.690 T:140031872165632 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2019-04-05 13:30:24.742 T:140030261573376 DEBUG: PLEX.kodimonitor: Method: Player.OnAVStart Data: {u'item': {u'type': u'song', u'title': u'Gruenstreifen'}, u'player': {u'playerid': 0, u'speed': 1}} 2019-04-05 13:30:25.009 T:140029951207168 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnAVStart - data: {"item":{"title":"Gruenstreifen","type":"song"},"player":{"playerid":0,"speed":1}} 2019-04-05 13:30:25.647 T:140028329789184 DEBUG: 127.0.0.1 - - [05/Apr/2019:13:30:25] "HEAD /track/radio HTTP/1.1" 200 - "" "Kodi/18.1 (X11; Linux x86_64) Ubuntu/18.04 App_Bitness/64 Version/18.1-Git:20190217-8cfdc89" 2019-04-05 13:30:25.647 T:140028329789184 DEBUG: . 2019-04-05 13:30:25.650 T:140030261573376 DEBUG: PLEX.kodimonitor: Kodi playing item properties: {u'title': u'Gruenstreifen', u'type': u'song', u'file': u'http://127.0.0.1:51100/track/radio', u'label': u'Gruenstreifen'} 2019-04-05 13:30:25.651 T:140030261573376 DEBUG: PLEX.kodi_db.music: Found wrong number of path ids: [] for path http://127.0.0.1:51100/track/, abort 2019-04-05 13:30:25.652 T:140030261573376 DEBUG: PLEX.kodimonitor: No Plex id obtained - aborting playback report 2019-04-05 13:30:25.752 T:140028239750912 NOTICE: plugin.audio.squeezebox --> playback stopped 2019-04-05 13:30:25.932 T:140028449568512 DEBUG: PLEX.subscribers: PKC playqueue is still initializing - skip update

croneter commented 5 years ago

Please upload an ENITRE debug log here on Github (just drop the file), according to https://github.com/croneter/PlexKodiConnect/wiki/How-to-Report-A-Bug

joswart commented 5 years ago

https://pastebin.com/DgdsQcBC

croneter commented 5 years ago

Nothing suspicious at all on the PlexKodiConnect side. Please take this up with the squeezebox team as it is a squeezebox issue