2023-08-23, 01:21
Hello,
Firstly I want to thank you for your great works, the add-on is so much useful in my scenario (AVR with Pre-out + Stereo amp, so I have to change between 3 profiles with different outputs). I'm modifying your codes to treat the DSD music (using a different output compared with another lossless extension in my case) and multi-channel music. It works for me, but I have a weird problem. Yesterday, when I was playing the DSD music songs (with .dsf or .dff extension), the method "onNotification" returned good information about the song so it entered the "auto_music" scenario without problem, but today (after some setting modifications I guess), it returned unknown type as below:
So now I have to treat it as "auto_unknown" type by duplicating the code in "auto_music". I'm pretty sure that this file was detected as a song yesterday, like all of my DSD files. Do you have any ideas why the method "onNotification" returning type "unknown" now In the logs, it seems that the player can open the file with appropriated audio decoder at this line
Thanks in advance for your help and I hope that I could contribute to your development.
Firstly I want to thank you for your great works, the add-on is so much useful in my scenario (AVR with Pre-out + Stereo amp, so I have to change between 3 profiles with different outputs). I'm modifying your codes to treat the DSD music (using a different output compared with another lossless extension in my case) and multi-channel music. It works for me, but I have a weird problem. Yesterday, when I was playing the DSD music songs (with .dsf or .dff extension), the method "onNotification" returned good information about the song so it entered the "auto_music" scenario without problem, but today (after some setting modifications I guess), it returned unknown type as below:
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers(smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf)
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2023-08-23 00:59:46.208 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2023-08-23 00:59:46.209 T:3650 debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 0 ms
2023-08-23 00:59:46.216 T:3650 debug <general>: CAddonDatabase: query SELECT addons.*, repo.addonID AS repoID FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon JOIN repo ON repo.id=addonlinkrepo.idRepo WHERE addonlinkrepo.idRepo IN (1) ORDER BY repo.addonID, addons.addonID returned 748 rows in 7 ms
2023-08-23 00:59:46.259 T:3650 debug <general>: CAddonDatabase::GetAddons took 50 ms
2023-08-23 00:59:46.261 T:3650 debug <general>: ADDONS: repository.xbmc.org - 748 addon(s) loaded
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: for video=false, audio=true
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2023-08-23 00:59:46.268 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
2023-08-23 00:59:46.268 T:3650 debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 0 ms
2023-08-23 00:59:46.273 T:3650 debug <general>: CAddonDatabase: query SELECT addons.*, repo.addonID AS repoID FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon JOIN repo ON repo.id=addonlinkrepo.idRepo WHERE addonlinkrepo.idRepo IN (1) ORDER BY repo.addonID, addons.addonID returned 748 rows in 4 ms
2023-08-23 00:59:46.313 T:3650 debug <general>: CAddonDatabase::GetAddons took 44 ms
2023-08-23 00:59:46.315 T:3650 debug <general>: ADDONS: repository.xbmc.org - 748 addon(s) loaded
2023-08-23 00:59:46.322 T:3650 debug <general>: CPlayerCoreFactory::GetPlayers: added 2 players
2023-08-23 00:59:46.323 T:3775 debug <general>: Thread JobWorker start, auto delete: true
2023-08-23 00:59:46.323 T:3776 debug <general>: Thread PAPlayer start, auto delete: false
2023-08-23 00:59:46.323 T:3775 debug <general>: CFileCache::Open - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> opening
2023-08-23 00:59:46.323 T:3650 debug <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2023-08-23 00:59:46.323 T:3776 debug <general>: PAPlayer:rocess - Playback started
2023-08-23 00:59:46.324 T:3650 debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 0 ms
2023-08-23 00:59:46.324 T:3775 debug <general>: CSMBFile::Open - opened smb://USERNAME[email protected]/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf, fd=10000
2023-08-23 00:59:46.326 T:3775 debug <general>: CFileCache::Open - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> source chunk size is 65536, setting cache chunk size to 65536
2023-08-23 00:59:46.326 T:3775 debug <general>: CFileCache::Open - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> using single memory cache sized 20971520 bytes
2023-08-23 00:59:46.328 T:3777 debug <general>: Thread FileCache start, auto delete: false
2023-08-23 00:59:46.329 T:3775 debug <general>: Open - probing detected format [dsf]
2023-08-23 00:59:46.329 T:3777 debug <general>: CFileCache:rocess - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> cache completely reset for seek to position 216629498
2023-08-23 00:59:46.329 T:3775 debug <general>: CFileCache::Seek - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> waiting for position 216694876
2023-08-23 00:59:46.329 T:3777 debug <general>: CFileCache:rocess - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> source read hit eof
2023-08-23 00:59:46.330 T:3650 debug <general>: CAddonDatabase: query SELECT addons.*, repo.addonID AS repoID FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon JOIN repo ON repo.id=addonlinkrepo.idRepo WHERE addonlinkrepo.idRepo IN (1) ORDER BY repo.addonID, addons.addonID returned 748 rows in 6 ms
2023-08-23 00:59:46.370 T:3650 debug <general>: CAddonDatabase::GetAddons took 45 ms
2023-08-23 00:59:46.372 T:3650 debug <general>: ADDONS: repository.xbmc.org - 748 addon(s) loaded
2023-08-23 00:59:46.379 T:3777 debug <general>: CFileCache:rocess - <smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf> cache completely reset for seek to position 28
2023-08-23 00:59:46.380 T:3775 debug <general>: Open - avformat_find_stream_info starting
2023-08-23 00:59:46.380 T:3775 debug <general>: ffmpeg[0x55cc9111dfb0]: [dsf] Estimating duration from bitrate, this may be inaccurate
2023-08-23 00:59:46.380 T:3775 debug <general>: Open - av_find_stream_info finished
2023-08-23 00:59:46.380 T:3775 debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-08-23 00:59:46.380 T:3775 info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder dsd_lsbf_planar
2023-08-23 00:59:46.381 T:3775 debug <general>: CSMBFile::Open - opened smb://USERNAME[email protected]/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf, fd=10001
2023-08-23 00:59:46.382 T:3650 debug <general>: CMusicGUIInfo::InitCurrentItem(smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf)
2023-08-23 00:59:46.383 T:3775 debug <general>: CSMBFile::Close closing fd 10001
2023-08-23 00:59:46.383 T:3775 debug <general>: file smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf could not be opened for tag reading
2023-08-23 00:59:46.383 T:3775 info <general>: CDVDAudioCodecFFmpeg::GetChannelMap - FFmpeg reported 2 channels, but the layout contains 0 - trying hints
2023-08-23 00:59:46.383 T:3775 debug <general>: SeekTime - seek ended up on time 0
2023-08-23 00:59:46.383 T:3650 info <general>: Skipped 1 duplicate messages..
2023-08-23 00:59:46.383 T:3650 debug <general>: LoadMusicTag: loading tag information for file: smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf
2023-08-23 00:59:46.384 T:3650 debug <general>: CSMBFile::Open - opened smb://USERNAME[email protected]/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf, fd=10001
2023-08-23 00:59:46.386 T:3650 debug <general>: ffmpeg[0x0]: Invalid return value 0 for stream protocol
2023-08-23 00:59:46.387 T:3650 info <general>: Skipped 1 duplicate messages..
2023-08-23 00:59:46.387 T:3650 debug <general>: CSMBFile::Close closing fd 10001
2023-08-23 00:59:46.403 T:3650 debug <general>: Loading additional tag info for file smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf
2023-08-23 00:59:46.404 T:3650 debug <general>: CSMBFile::Open - opened smb://USERNAME[email protected]/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf, fd=10001
2023-08-23 00:59:46.406 T:3650 debug <general>: ffmpeg[0x0]: Invalid return value 0 for stream protocol
2023-08-23 00:59:46.407 T:3650 info <general>: Skipped 1 duplicate messages..
2023-08-23 00:59:46.407 T:3650 debug <general>: CSMBFile::Close closing fd 10001
2023-08-23 00:59:46.407 T:3650 debug <general>: CPlayerGUIInfo::InitCurrentItem(smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf)
2023-08-23 00:59:46.483 T:3745 debug <general>: [Audio Profiles Service] MONITOR METHOD: Player.OnPlay DATA: {'item': {'type': 'unknown'}, 'player': {'playerid': 0, 'speed': 1}}
2023-08-23 00:59:46.483 T:3745 debug <general>: [Audio Profiles Service] the type is: unknown
2023-08-23 00:59:46.484 T:3745 debug <general>: [Audio Profiles Service] the playing file is: smb://192.168.1.252/homes/hakki/Lossless/DSD/01 - David Elias - The Window - Vision of Her (DSD64).dsf
2023-08-23 00:59:46.484 T:3745 debug <general>: [Audio Profiles Service] got auto_unknown from the content auto switch
2023-08-23 00:59:46.484 T:3745 debug <general>: [Audio Profiles Service] got a content autoswitch of auto_unknown
So now I have to treat it as "auto_unknown" type by duplicating the code in "auto_music". I'm pretty sure that this file was detected as a song yesterday, like all of my DSD files. Do you have any ideas why the method "onNotification" returning type "unknown" now In the logs, it seems that the player can open the file with appropriated audio decoder at this line
2023-08-23 00:59:46.380 T:3775 info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder dsd_lsbf_planar
Thanks in advance for your help and I hope that I could contribute to your development.