2022-03-24 10:09:36.957 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Update 2022-03-06 10:12:38
2022-03-24 10:09:36.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Full Update 2022-03-06 10:12:38
2022-03-24 10:09:36.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: version 3
2022-03-24 10:09:36.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: status IDLE
2022-03-24 10:09:36.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: update interval 7200
2022-03-24 10:09:36.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Update Mode "Manual"
2022-03-24 10:09:36.957 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: nothing to do
2022-03-24 10:09:37.345 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:09:48.249 T:1135     INFO <CWebserver[8080]>: Skipped 18 duplicate messages..
2022-03-24 10:09:48.249 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:09:59.220 T:1135     INFO <CWebserver[8080]>: Skipped 18 duplicate messages..
2022-03-24 10:09:59.220 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:05.922 T:1031     INFO <general>: Skipped 14 duplicate messages..
2022-03-24 10:10:05.922 T:1031    DEBUG <general>: EventClient: button name "down" map "R1" pressed
2022-03-24 10:10:05.925 T:996     DEBUG <general>: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2022-03-24 10:10:05.940 T:1140    DEBUG <general>: Thread JobWorker start, auto delete: true
2022-03-24 10:10:05.951 T:1140     INFO <general>: Skipped 3 duplicate messages..
2022-03-24 10:10:05.951 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:05.956 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:05.961 T:1142    DEBUG <general>: ffmpeg[0x2b29fe0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:05.973 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:05.978 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.008 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.015 T:1142    DEBUG <general>: ffmpeg[0x2b29fe0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.029 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.039 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.060 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.064 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.067 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.070 T:1142    DEBUG <general>: ffmpeg[0x2b29fe0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.259 T:1031    DEBUG <general>: EventClient: button name "down" map "R1" pressed
2022-03-24 10:10:06.272 T:996     DEBUG <general>: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2022-03-24 10:10:06.285 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.288 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.292 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.297 T:1142    DEBUG <general>: ffmpeg[0x2b29fe0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.301 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.304 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.308 T:1142    DEBUG <general>: ffmpeg[0x2b29fe0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.311 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.321 T:1141    DEBUG <general>: ffmpeg[0x3ac48d0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.324 T:1143    DEBUG <general>: ffmpeg[0x2b50f98X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:06.332 T:1140    DEBUG <general>: ffmpeg[0x3b5d550X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2022-03-24 10:10:07.041 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:07.957 T:1022     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:07.957 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Database driver: Internal (sqlite)
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:StoreSQLite]: StoreSQLite DBFile: /storage/.kodi/userdata/addon_data/plugin.video.mediathekview/filmliste-v3.db
2022-03-24 10:10:07.958 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Update 2022-03-06 10:12:38
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Full Update 2022-03-06 10:12:38
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: version 3
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: status IDLE
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: update interval 7200
2022-03-24 10:10:07.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Update Mode "Manual"
2022-03-24 10:10:07.958 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: nothing to do
2022-03-24 10:10:08.099 T:1031    DEBUG <general>: EventClient: button name "down" map "R1" pressed
2022-03-24 10:10:08.122 T:996     DEBUG <general>: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2022-03-24 10:10:08.609 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:08.905 T:1031     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:08.905 T:1031    DEBUG <general>: EventClient: button name "up" map "R1" pressed
2022-03-24 10:10:08.911 T:996     DEBUG <general>: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2022-03-24 10:10:10.173 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:11.188 T:1031     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:11.188 T:1031    DEBUG <general>: EventClient: button name "right" map "R1" pressed
2022-03-24 10:10:11.193 T:996     DEBUG <general>: HandleKey: 168 (0xa8, obc87) pressed, action is Right
2022-03-24 10:10:11.637 T:1031    DEBUG <general>: EventClient: button name "down" map "R1" pressed
2022-03-24 10:10:11.639 T:996     DEBUG <general>: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2022-03-24 10:10:11.735 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:17.084 T:1031     INFO <general>: Skipped 12 duplicate messages..
2022-03-24 10:10:17.084 T:1031    DEBUG <general>: EventClient: button name "select" map "R1" pressed
2022-03-24 10:10:17.089 T:996     DEBUG <general>: HandleKey: 11 (0x0b, obc244) pressed, action is Select
2022-03-24 10:10:17.091 T:996     DEBUG <general>: Activating window ID: 10502
2022-03-24 10:10:17.401 T:996     DEBUG <general>: ------ Window Deinit (Home.xml) ------
2022-03-24 10:10:17.401 T:996     DEBUG <general>: FreeVisualisation() done
2022-03-24 10:10:17.427 T:996     DEBUG <general>: ------ Window Init (MyMusicNav.xml) ------
2022-03-24 10:10:17.428 T:996      INFO <general>: Loading skin file: MyMusicNav.xml, load type: KEEP_IN_MEMORY
2022-03-24 10:10:17.540 T:996     DEBUG <general>: CGUIMediaWindow::GetDirectory (musicdb://recentlyplayedalbums/88/)
2022-03-24 10:10:17.540 T:996     DEBUG <general>:   ParentPath = []
2022-03-24 10:10:17.548 T:996     DEBUG <general>: GetSongsFullByWhere query = SELECT songview.*, songartistview.* FROM songview JOIN songartistview ON songartistview.idSong = songview.idSong WHERE songview.idAlbum = 88 ORDER BY songview.idSong, songartistview.idRole, songartistview.iOrder
2022-03-24 10:10:17.551 T:996     DEBUG <general>: GetSongsFullByWhere: Time to fill list with songs 2ms query took 2ms
2022-03-24 10:10:17.555 T:996     DEBUG <general>: Custom album format = [%B]
2022-03-24 10:10:17.564 T:1144     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:17.564 T:1144    DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2022-03-24 10:10:17.574 T:1144    DEBUG <general>: Thread BackgroundLoader 3429909120 terminating
2022-03-24 10:10:17.971 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:18.437 T:1031     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:18.437 T:1031    DEBUG <general>: EventClient: button name "down" map "R1" pressed
2022-03-24 10:10:18.445 T:996     DEBUG <general>: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2022-03-24 10:10:18.889 T:1031    DEBUG <general>: EventClient: button name "select" map "R1" pressed
2022-03-24 10:10:18.889 T:996     DEBUG <general>: HandleKey: 11 (0x0b, obc244) pressed, action is Select
2022-03-24 10:10:18.890 T:996     DEBUG <general>: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 88  ORDER BY albumartistview.iOrder
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers(smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac)
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
2022-03-24 10:10:18.894 T:996     DEBUG <general>: CPlayerCoreFactory::GetPlayers: added 2 players
2022-03-24 10:10:18.895 T:1143    DEBUG <general>: CFileCache::Open - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> opening
2022-03-24 10:10:18.896 T:1145    DEBUG <general>: Thread PAPlayer start, auto delete: false
2022-03-24 10:10:18.896 T:996     DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2022-03-24 10:10:18.896 T:1145    DEBUG <general>: PAPlayer::Process - Playback started
2022-03-24 10:10:18.905 T:1143    DEBUG <general>: CSMBFile::Open - opened smb://USERNAME:PASSWORD@192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac, fd=10000
2022-03-24 10:10:18.922 T:996     DEBUG <general>: CMusicGUIInfo::InitCurrentItem(musicdb://recentlyplayedalbums/88/-2/751.flac?albumid=88)
2022-03-24 10:10:18.925 T:1143    DEBUG <general>: CFileCache::Open - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> source chunk size is 65536, setting cache chunk size to 65536
2022-03-24 10:10:18.925 T:1143    DEBUG <general>: CFileCache::Open - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> using single memory cache sized 20971520 bytes
2022-03-24 10:10:18.925 T:1146    DEBUG <general>: Thread FileCache start, auto delete: false
2022-03-24 10:10:18.928 T:996     DEBUG <general>: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 88  ORDER BY albumartistview.iOrder
2022-03-24 10:10:18.929 T:996     DEBUG <general>: Loading additional tag info for file smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac
2022-03-24 10:10:18.961 T:1143    DEBUG <general>: Open - probing detected format [flac]
2022-03-24 10:10:18.967 T:996     DEBUG <general>: CSMBFile::Open - opened smb://USERNAME:PASSWORD@192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac, fd=10001
2022-03-24 10:10:18.986 T:1143    DEBUG <general>: Open - avformat_find_stream_info starting
2022-03-24 10:10:18.987 T:1143    DEBUG <general>: Open - av_find_stream_info finished
2022-03-24 10:10:18.988 T:1143    DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2022-03-24 10:10:18.988 T:1143    DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2022-03-24 10:10:18.988 T:1143     INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder flac
2022-03-24 10:10:19.114 T:1143    DEBUG <general>: CSMBFile::Open - opened smb://USERNAME:PASSWORD@192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac, fd=10002
2022-03-24 10:10:19.321 T:996     DEBUG <general>: unrecognized XipComment name: CATALOGNUMBER
2022-03-24 10:10:19.321 T:996     DEBUG <general>: unrecognized XipComment name: DESCRIPTION
2022-03-24 10:10:19.321 T:996     DEBUG <general>: unrecognized XipComment name: DISCTOTAL
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: ISRC
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: MEDIA
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: MUSICBRAINZ_RELEASETRACKID
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: RELEASECOUNTRY
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: SCRIPT
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: TOTALDISCS
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: TOTALTRACKS
2022-03-24 10:10:19.322 T:996     DEBUG <general>: unrecognized XipComment name: TRACKTOTAL
2022-03-24 10:10:19.322 T:996     DEBUG <general>: CSMBFile::Close closing fd 10001
2022-03-24 10:10:19.365 T:996     DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(musicdb://recentlyplayedalbums/88/-2/751.flac?albumid=88)
2022-03-24 10:10:19.463 T:1028     INFO <general>: Librespot: Kodi playback started
2022-03-24 10:10:19.466 T:996     DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2022-03-24 10:10:19.509 T:1061     INFO <general>: Librespot: suspended sink 1
2022-03-24 10:10:19.509 T:1061     INFO <general>: Librespot: librespot stopped
2022-03-24 10:10:19.509 T:1061     INFO <general>: Librespot: librespot thread stopped
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: CATALOGNUMBER
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: DESCRIPTION
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: DISCTOTAL
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: ISRC
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: MEDIA
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: MUSICBRAINZ_RELEASETRACKID
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: RELEASECOUNTRY
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: SCRIPT
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: TOTALDISCS
2022-03-24 10:10:19.532 T:1143    DEBUG <general>: unrecognized XipComment name: TOTALTRACKS
2022-03-24 10:10:19.533 T:1143    DEBUG <general>: unrecognized XipComment name: TRACKTOTAL
2022-03-24 10:10:19.533 T:1143    DEBUG <general>: CSMBFile::Close closing fd 10002
2022-03-24 10:10:19.545 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:19.585 T:1146     INFO <general>: Skipped 2 duplicate messages..
2022-03-24 10:10:19.585 T:1146    DEBUG <general>: CFileCache::Process - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> cache completely reset for seek to position 21367533
2022-03-24 10:10:19.585 T:1143    DEBUG <general>: CFileCache::Seek - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> waiting for position 21432044
2022-03-24 10:10:19.601 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:19.608 T:1146    DEBUG <general>: CFileCache::Process - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> source read hit eof
2022-03-24 10:10:19.628 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:19.658 T:1146    DEBUG <general>: CFileCache::Process - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> cache completely reset for seek to position 101005
2022-03-24 10:10:19.692 T:1146    DEBUG <general>: CFileCache::Process - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> cache completely reset for seek to position 100912
2022-03-24 10:10:19.727 T:1146    DEBUG <general>: CFileCache::Process - <smb://192.168.2.6:445/Multimedia/Musik/Current 93/The Light Is Leaving Us All/01 The Birds Are Sweetly Singing.flac> cache completely reset for seek to position 100911
2022-03-24 10:10:19.727 T:1143    DEBUG <general>: SeekTime - seek ended up on time 0
2022-03-24 10:10:19.812 T:1143     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:19.812 T:1143     INFO <general>: AudioDecoder: File is queued
2022-03-24 10:10:19.814 T:1143    DEBUG <general>: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000
2022-03-24 10:10:19.815 T:1001    DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2022-03-24 10:10:19.823 T:1143     INFO <general>: PAPlayer::PrepareStream - Ready
2022-03-24 10:10:19.833 T:1145    DEBUG <general>: OnAVStarted: CApplication::OnAVStarted
2022-03-24 10:10:19.889 T:996     DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2022-03-24 10:10:21.211 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:32.626 T:1135     INFO <CWebserver[8080]>: Skipped 39 duplicate messages..
2022-03-24 10:10:32.626 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:36.239 T:1146     INFO <general>: Skipped 20 duplicate messages..
2022-03-24 10:10:36.239 T:1146    DEBUG <general>: Thread FileCache 3382997632 terminating
2022-03-24 10:10:36.244 T:1145    DEBUG <general>: CSMBFile::Close closing fd 10000
2022-03-24 10:10:36.256 T:1143    DEBUG <general>: DoWork - Saving file state for audio item musicdb://recentlyplayedalbums/88/-2/751.flac?albumid=88
2022-03-24 10:10:36.256 T:1001    DEBUG <general>: CActiveAE::DiscardStream - audio stream deleted
2022-03-24 10:10:36.256 T:1001    DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2022-03-24 10:10:36.265 T:1145     INFO <general>: Skipped 2 duplicate messages..
2022-03-24 10:10:36.265 T:1145    DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2022-03-24 10:10:36.265 T:1145    DEBUG <general>: Thread PAPlayer 3416380032 terminating
2022-03-24 10:10:36.391 T:1029    DEBUG <CAddonSettings[service.upnext]>: trying to load setting definitions from old format...
2022-03-24 10:10:36.466 T:1028     INFO <general>: Skipped 1 duplicate messages..
2022-03-24 10:10:36.466 T:1028     INFO <general>: Librespot: Kodi playback stopped
2022-03-24 10:10:36.467 T:1149     INFO <general>: Librespot: librespot thread started
2022-03-24 10:10:36.487 T:1024    DEBUG <general>: [plugin.video.youtube] PlaybackMonitor active threads: ||
2022-03-24 10:10:36.509 T:1149     INFO <general>: Librespot: librespot started
2022-03-24 10:10:36.519 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot] librespot 0.3.1 1d5c210b2d (Built on 2021-12-24, Build ID: 9ivxgKm5, Profile: release)
2022-03-24 10:10:36.546 T:1149     INFO <general>: Librespot: *** WARNING *** The program 'librespot' uses the Apple Bonjour compatibility layer of Avahi.
2022-03-24 10:10:36.546 T:1149     INFO <general>: Librespot: *** WARNING *** Please fix your application to use the native API of Avahi!
2022-03-24 10:10:36.547 T:1149     INFO <general>: Librespot: *** WARNING *** For more information see <http://0pointer.de/blog/projects/avahi-compat.html>
2022-03-24 10:10:36.611 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_core::session] Connecting to AP "ap-gew1.spotify.com:4070"
2022-03-24 10:10:36.627 T:1001    DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2022-03-24 10:10:36.778 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_core::session] Authenticated as "o34zxc1wxabxdydk2z0cgleab" !
2022-03-24 10:10:36.779 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_playback::mixer::softmixer] Mixing with softvol and volume control: Log(60.0)
2022-03-24 10:10:36.779 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_playback::convert] Converting with ditherer: tpdf
2022-03-24 10:10:36.779 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_playback::audio_backend::pulseaudio] Using PulseAudioSink with format: S16
2022-03-24 10:10:36.780 T:1149     INFO <general>: Librespot: [2022-03-24T09:10:36Z INFO  librespot_core::session] Country: "DE"
2022-03-24 10:10:36.780 T:1149     INFO <general>: Librespot: country=DE
2022-03-24 10:10:37.571 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc
2022-03-24 10:10:38.958 T:1022     INFO <general>: Skipped 4 duplicate messages..
2022-03-24 10:10:38.958 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Database driver: Internal (sqlite)
2022-03-24 10:10:38.959 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:StoreSQLite]: StoreSQLite DBFile: /storage/.kodi/userdata/addon_data/plugin.video.mediathekview/filmliste-v3.db
2022-03-24 10:10:38.959 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Update 2022-03-06 10:12:38
2022-03-24 10:10:38.959 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Last Full Update 2022-03-06 10:12:38
2022-03-24 10:10:38.959 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: version 3
2022-03-24 10:10:38.959 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: status IDLE
2022-03-24 10:10:38.960 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: update interval 7200
2022-03-24 10:10:38.960 T:1022    DEBUG <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: Update Mode "Manual"
2022-03-24 10:10:38.960 T:1022     INFO <general>: [plugin.video.mediathekview-1.0.0+matrix.1:MediathekViewUpdater]: nothing to do
2022-03-24 10:10:39.140 T:1135    DEBUG <CWebserver[8080]>: request received for /jsonrpc