-- Logs begin at Sat 2025-11-15 07:00:41 UTC, end at Sun 2026-02-22 17:09:07 UTC. -- Feb 22 17:08:00 ifi-gory-stream volumiologrotate[456]: ls: cannot access '/var/log/samba/log.wb-GORY': No such file or directory Feb 22 17:08:00 ifi-gory-stream volumiologrotate[456]: ls: cannot access 'STREAM': No such file or directory Feb 22 17:08:00 ifi-gory-stream volumio[1136]: info: ++++--++++ browseLibrary: {"uri":"music-library/USB/Seagate_Portable_Drive"} Feb 22 17:08:00 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 17:08:00 ifi-gory-stream volumio[1136]: info: CURURI: music-library/USB/Seagate_Portable_Drive Feb 22 17:08:00 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE AudioRenderer: [0xab0f4bd8]: Playback position: 179598 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] INFO ProtocolHandler: [0xab0f50f8]: Need to send state update Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] INFO QConnectMessageSender: [0xab2f5e28]: Sending StateUpdated message Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Playback state: Playing Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current position: 179598 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Duration: 323493 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Queue version: 2.2 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current queue item ID: 1 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.708 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Next queue item ID: 2 Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.709 [2030.2030] INFO QwspMessageSender: [0xab2f5e18]: Sending Payload message Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Protocol: QConnect Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Channel: Backend Feb 22 17:08:02 ifi-gory-stream play[2004]: 20260222 17:08:02.709 [2030.2030] TRACE PlaybackPositionEstimator: [0xab0f5688]: Synced playback position: 179598ms Feb 22 17:08:06 ifi-gory-stream volumio[1136]: info: ++++--++++ browseLibrary: {"uri":"artists://"} Feb 22 17:08:06 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 17:08:06 ifi-gory-stream volumio[1136]: info: CURURI: artists:// Feb 22 17:08:06 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.708 [2030.2030] TRACE AudioRenderer: [0xab0f4bd8]: Playback position: 189597 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.708 [2030.2030] INFO ProtocolHandler: [0xab0f50f8]: Need to send state update Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] INFO QConnectMessageSender: [0xab2f5e28]: Sending StateUpdated message Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Playback state: Playing Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current position: 189597 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Duration: 323493 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Queue version: 2.2 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current queue item ID: 1 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Next queue item ID: 2 Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] INFO QwspMessageSender: [0xab2f5e18]: Sending Payload message Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Protocol: QConnect Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Channel: Backend Feb 22 17:08:12 ifi-gory-stream play[2004]: 20260222 17:08:12.710 [2030.2030] TRACE PlaybackPositionEstimator: [0xab0f5688]: Synced playback position: 189597ms Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ++++--++++ browseLibrary: {"uri":"artists://ASHBY%2CDOROTHY"} Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: CURURI: artists://ASHBY%2CDOROTHY Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Preloading song: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098414&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-01.Pawky.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098442&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-02.Moonlight%20In%20Vermont.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098489&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-03.Back%20Talk.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098540&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-04.Dancing%20In%20The%20Dark.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098594&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-05.Charmain.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098641&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-06.Jollity.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: Exploding uri music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac in service mpd Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: ALBUMART /albumart?cacheid=929_1771780098701&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=%2Fmnt%2FUSB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D%2FASHBY%2CDOROTHY-1958-Hip%20Harp-07.There's%20A%20Small%20Hotel.flac&metadata=false Feb 22 17:08:18 ifi-gory-stream volumio[1136]: info: URI /mnt/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE AudioRenderer: [0xab0f4bd8]: Playback position: 199599 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] INFO ProtocolHandler: [0xab0f50f8]: Need to send state update Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] INFO QConnectMessageSender: [0xab2f5e28]: Sending StateUpdated message Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Playback state: Playing Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current position: 199599 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Duration: 323493 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Queue version: 2.2 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Current queue item ID: 1 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QConnectMessageSender: [0xab2f5e28]: Next queue item ID: 2 Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] INFO QwspMessageSender: [0xab2f5e18]: Sending Payload message Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Protocol: QConnect Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.709 [2030.2030] TRACE QwspMessageSender: [0xab2f5e18]: Channel: Backend Feb 22 17:08:22 ifi-gory-stream play[2004]: 20260222 17:08:22.710 [2030.2030] TRACE PlaybackPositionEstimator: [0xab0f5688]: Synced playback position: 199599ms Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ++++--++++ playItemsList: {"item":{"service":"mpd","type":"song","title":"Pawky","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098263&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac","id":"id-mly04hcn3n0v5buxs"},"list":[{"service":"mpd","type":"song","title":"Pawky","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098263&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac","id":"id-mly04hcn3n0v5buxs"},{"service":"mpd","type":"song","title":"Moonlight In Vermont","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098264&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac","id":"id-mly04hcn7ik22ai4r"},{"service":"mpd","type":"song","title":"Back Talk","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098264&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac","id":"id-mly04hcnhcuhutzij"},{"service":"mpd","type":"song","title":"Dancing In The Dark","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098265&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac","id":"id-mly04hcnd118ym5a8"},{"service":"mpd","type":"song","title":"Charmain","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098265&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac","id":"id-mly04hcnddou4kt2q"},{"service":"mpd","type":"song","title":"Jollity","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098265&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac","id":"id-mly04hcnpi80ph06g"},{"service":"mpd","type":"song","title":"There's A Small Hotel","artist":"ASHBY,DOROTHY","album":"Hip Harp","year":"1958","albumart":"http://192.168.2.156/albumart?cacheid=929_1771780098265&web=ASHBY%2CDOROTHY/Hip%20Harp/extralarge&path=USB%2FSeagate_Portable_Drive%2FBACKUP%202024%2FASHBY%2CDOROTHY-1958-Hip%20Harp%20%5BFLAC%5D&icon=dot-circle-o&metadata=false","uri":"music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac","id":"id-mly04hcnpfqozh8b6"}],"index":0} Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::ClearQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::stop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::serviceStop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::serviceStop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: error: Could not retrieve plugin music_service qobuz_connect Feb 22 17:08:27 ifi-gory-stream volumio[1136]: error: WARNING: No stop method for service qobuz_connect Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::clearPlayQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::saveQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::addQueueItems Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::addQueueItems Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::saveQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ConvSwitch: Sent Play Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPlay Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::play index 0 Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::addQueueItems Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::addQueueItems Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.759 [2030.2030] DEBUG RendererActivationCommandSequencer: [0xab0f53b8]: Enqueuing command: Deactivate + Disconnect Renderer Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Executing command: Deactivate + Disconnect Renderer Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Disconnecting from the server Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] INFO CloudClient: [0xab0f4f90]: Disconnecting from the cloud Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] DEBUG WebsocketClient: [0xab0f7b08]: Freeing Websocket client Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] INFO CloudClient: [0xab0f4f90]: Connection closed Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] DEBUG QConnectMessageSender: [0xab2f5e28]: Freeing QConnect Message Sender Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] DEBUG QwspMessageSender: [0xab2f5e18]: Freeing QWSP Message Sender Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.760 [2030.2030] TRACE ProtocolHandler: [0xab0f50f8]: Stopping periodic state updates Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-02.Moonlight In Vermont.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-03.Back Talk.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-04.Dancing In The Dark.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-05.Charmain.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-06.Jollity.flac Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.768 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Stopping playback session maintenance Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.768 [2030.2030] INFO PlaybackSessionManager: [0xab0f4720]: Stopping playback session maintenance Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Making the renderer inactive Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] INFO ActiveStateManager: [0xab0f3348]: Setting new active state: inactive Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Media Engine needs to be stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] INFO MediaEngine: [0xab0f49b8]: Stopping playback, clearing tracks Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Enqueuing command: Stop Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] INFO MediaEngine: [0xab0f49b8]: Reseting Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Enqueuing command: Reset Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Enqueuing command: SetCurrentTrack Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Enqueuing command: SetNextTrack Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.769 [2030.2030] INFO SampleApp: Renderer is now inactive Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Executing command: Stop Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] INFO AudioRenderer: [0xab0f4bd8]: Stopping audio renderer Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] DEBUG PlaybackProgressManager: [0xab0f4c30]: Audio playback is stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] TRACE AudioRenderer: [0xab0f4bd8]: State changed: Playing -> Stopping Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Audio renderer state changed: Playing -> Stopping Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] INFO SampleApp: Stopping playback Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] DEBUG AudioRenderer: [0xab0f4bd8]: Transition to stopped state has been acknowledged Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Adding Item to queue: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Using cached record of: music-library/USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-07.There's A Small Hotel.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::stop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::stPlaybackTimer Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::pushState Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] TRACE AudioRenderer: [0xab0f4bd8]: State changed: Stopping -> Stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.770 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Audio renderer state changed: Stopping -> Stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.771 [2030.2030] TRACE MediaEngine: [0xab0f49b8]: State changed: Playing -> Stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.771 [2030.2030] INFO AudioStreamManager: [0xab0f4aa0]: Clearing all streams Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.771 [2030.2030] INFO AudioStreamManager: [0xab0f4aa0]: [Stream 20]: Disposing of audio stream Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.771 [2030.2030] DEBUG NetworkActivityTracker: [0xab0f33b8]: Network activity finished Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.771 [2030.2030] INFO ContentFetcher: [0xab304eb8]: Canceling download Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.787 [2030.2030] DEBUG Ringbuffer: [0xab5751c8]: Freeing Audio Ringbuffer Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.787 [2030.2030] DEBUG AudioStreamInfoFetcher: [0xab253318]: Freeing Audio Stream Info Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG MetadataFetcher: [0xab4fd0d0]: Freeing Metadata Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG PlaybackUrlFetcher: [0xab190348]: Freeing Playback URL Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG AudioStreamInfoFetcher: [0xab0fc8a0]: Freeing Audio Stream Info Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG MetadataFetcher: [0xab27de80]: Freeing Metadata Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG PlaybackUrlFetcher: [0xab1e4b58]: Freeing Playback URL Fetcher Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Confirming command: Stop Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] INFO SampleApp: Playback state changed: Stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] INFO StreamingEventReporter: [0xab0f5510]: Reporting streaming ended event Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] TRACE StreamingEventReporter: [0xab0f5510]: End position: 204677ms Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] TRACE StreamingEventReporter: [0xab0f5510]: Duration: 204677ms Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG StreamingEventReporter: [0xab0f5510]: Sending streaming reports Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] DEBUG NetworkActivityTracker: [0xab0f33b8]: Network activity started Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.788 [2030.2030] INFO HttpDownloader: [0xab0f5568]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.789 [2030.2030] DEBUG UserInactivityManager: [0xab0f5750]: Renderer is active but not playing, will deactivate in 3600s Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.789 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Current stream changed state: Running -> Nonexistent Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.789 [2030.2030] INFO SampleApp: [Stream 20]: Audio stream has been disposed of Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.789 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Executing command: Reset Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.789 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Confirming command: Reset Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::serviceStop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::serviceStop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ControllerMpd::stop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::saveQueue Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ConvSwitch: Get message: success Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Media Engine has been stopped Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Invalidating track properties and device audio format Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Current stream changed state: Info Retrieved -> Nonexistent Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Executing command: SetCurrentTrack Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] INFO MediaEngine: [0xab0f49b8]: Setting current track: NONE Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Confirming command: SetCurrentTrack Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Waiting for the network activity to finish Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.864 [2030.2030] DEBUG MediaEngine: [0xab0f49b8]: Executing command: SetNextTrack Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.865 [2030.2030] INFO MediaEngine: [0xab0f49b8]: Setting next track: NONE Feb 22 17:08:27 ifi-gory-stream play[2004]: 20260222 17:08:27.865 [2030.2030] DEBUG MediaEngineCommandSequencer: [0xab0f4d30]: Confirming command: SetNextTrack Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: sendMpdCommand stop took 64 milliseconds Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::play index undefined Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CoreStateMachine::startPlaybackTimer Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: sendMpdCommand stop took 5 milliseconds Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Feb 22 17:08:27 ifi-gory-stream volumio[1136]: ---------------------------- MPD announces system playlist update Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Ignoring MPD Status Update Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: sendMpdCommand clear took 16 milliseconds Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac" Feb 22 17:08:27 ifi-gory-stream volumio[1136]: error: updateQueue error: null Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Feb 22 17:08:27 ifi-gory-stream volumio[1136]: ---------------------------- MPD announces system playlist update Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: Ignoring MPD Status Update Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ------------------------------ 20ms Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: sendMpdCommand add "USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac" took 13 milliseconds Feb 22 17:08:27 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: ------------------------------ 34ms Feb 22 17:08:27 ifi-gory-stream volumio[1136]: info: sendMpdCommand play took 28 milliseconds Feb 22 17:08:27 ifi-gory-stream kernel: wm8804 0-003b: wm8804_hw_params rate: 0xac44 format: 0x2 Feb 22 17:08:28 ifi-gory-stream kernel: dwmmc_rockchip ff5f0000.dwmmc: Unexpected interrupt latency Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: Feb 22 17:08:28 ifi-gory-stream volumio[1136]: ---------------------------- MPD announces state update: player Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ControllerMpd::getState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: Feb 22 17:08:28 ifi-gory-stream volumio[1136]: ---------------------------- MPD announces state update: player Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ControllerMpd::getState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: sendMpdCommand status took 10 milliseconds Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::parseState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: sendMpdCommand status took 17 milliseconds Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::parseState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ControllerMpd::pushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::servicePushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":755,"duration":424,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"554 Kbps","isStreaming":false,"title":"Pawky","artist":"ASHBY,DOROTHY","album":"Hip Harp","uri":"USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac","trackType":"flac"} Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: CURRENT POSITION 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ------------------------------ 61ms Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ControllerMpd::pushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::servicePushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":755,"duration":424,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"554 Kbps","isStreaming":false,"title":"Pawky","artist":"ASHBY,DOROTHY","album":"Hip Harp","uri":"USB/Seagate_Portable_Drive/BACKUP 2024/ASHBY,DOROTHY-1958-Hip Harp [FLAC]/ASHBY,DOROTHY-1958-Hip Harp-01.Pawky.flac","trackType":"flac"} Feb 22 17:08:28 ifi-gory-stream volumio[1136]: verbose: CURRENT POSITION 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::pushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreStateMachine::pushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 17:08:28 ifi-gory-stream volumio[1136]: info: ------------------------------ 93ms Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.387 [2030.2030] DEBUG StreamingEventReporter: [0xab0f5510]: Streaming report submitted successfully Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.387 [2030.2030] DEBUG NetworkActivityTracker: [0xab0f33b8]: Network activity finished Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.387 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Network activity has finished Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] DEBUG RendererActivationController: [0xab0f5310]: Clearing tokens Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] INFO EndpointManager: [0xab0f4330]: API endpoint has been cleared Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] TRACE EndpointManager: [0xab0f4340]: API endpoint's token state changed: Valid -> Invalid Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] INFO EndpointManager: [0xab0f4330]: QConnect endpoint has been cleared Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] TRACE EndpointManager: [0xab0f4428]: QConnect endpoint's token state changed: Valid -> Invalid Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] TRACE RendererActivationController: [0xab0f5310]: State changed: Active -> Inactive (Disconnected) Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] DEBUG RendererActivationCommandSequencer: [0xab0f53b8]: Confirming command: Deactivate + Disconnect Renderer Feb 22 17:08:28 ifi-gory-stream play[2004]: 20260222 17:08:28.388 [2030.2030] DEBUG UserInactivityManager: [0xab0f5750]: Renderer is inactive and disconnected Feb 22 17:08:29 ifi-gory-stream volumio[1136]: info: MetaClient: Sent Meta Feb 22 17:08:29 ifi-gory-stream volumio[1136]: info: MetaClient: Get message: success Feb 22 17:08:29 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:29 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:29 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:30 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:30 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:30 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:31 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:31 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:31 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:32 ifi-gory-stream volumio[1136]: info: ++++--++++ browseLibrary: {"uri":"favourites"} Feb 22 17:08:32 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:32 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:32 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:32 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:33 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:33 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:33 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:34 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:34 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:34 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:35 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:35 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:35 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:36 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:36 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:36 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:36 ifi-gory-stream volumio[1136]: info: ++++--++++ browseLibrary: {"uri":"favourites"} Feb 22 17:08:36 ifi-gory-stream volumio[1136]: info: Preload queue cleared Feb 22 17:08:37 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:37 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:37 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:38 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:38 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:38 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:39 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:39 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:39 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:40 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:40 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:40 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:41 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:41 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:41 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:42 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:42 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:42 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:43 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:43 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:43 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:44 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:44 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:44 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:45 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:45 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:45 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:46 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:46 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:46 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:47 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:47 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:47 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:48 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:48 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:48 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:49 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:50 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:50 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:50 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:51 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:51 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:51 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:52 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:52 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:52 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: ++++--++++ callMethod: {"type":"controller","endpoint":"audio_interface/alsa_controller","method":"saveResamplingOpts","data":{"resampling":true,"resampling_target_bitdepth":{"value":"24","label":"24"},"resampling_target_samplerate":{"value":"*","label":"Native"},"resampling_quality":{"value":"very high","label":"Very high"}}} Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Feb 22 17:08:53 ifi-gory-stream sudo[8798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 17:08:53 ifi-gory-stream sudo[8800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Feb 22 17:08:53 ifi-gory-stream sudo[8798]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 17:08:53 ifi-gory-stream sudo[8800]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:08:53 ifi-gory-stream sudo[8798]: pam_unix(sudo:session): session closed for user root Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: MPD Permissions set Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream systemd[1]: mpd.socket: Succeeded. Feb 22 17:08:53 ifi-gory-stream systemd[1]: Closed mpd.socket. Feb 22 17:08:53 ifi-gory-stream systemd[1]: Starting Internet Detect for iFi Streamer... Feb 22 17:08:53 ifi-gory-stream systemd[1]: Stopping Music Player Daemon... Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:08:53 ifi-gory-stream systemd[1]: ifi-system-internet.service: Succeeded. Feb 22 17:08:53 ifi-gory-stream systemd[1]: Started Internet Detect for iFi Streamer. Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 17:08:53 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:54 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:55 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:55 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:55 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:55 ifi-gory-stream systemd[1]: mpd.service: Succeeded. Feb 22 17:08:55 ifi-gory-stream systemd[1]: Stopped Music Player Daemon. Feb 22 17:08:55 ifi-gory-stream sudo[8800]: pam_unix(sudo:session): session closed for user root Feb 22 17:08:56 ifi-gory-stream sudo[8843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Feb 22 17:08:56 ifi-gory-stream sudo[8843]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:08:56 ifi-gory-stream systemd[1]: Listening on mpd.socket. Feb 22 17:08:56 ifi-gory-stream systemd[1]: Starting Music Player Daemon... Feb 22 17:08:56 ifi-gory-stream sudo[8845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 17:08:56 ifi-gory-stream sudo[8845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:08:56 ifi-gory-stream sudo[8845]: pam_unix(sudo:session): session closed for user root Feb 22 17:08:56 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:56 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:56 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:57 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:57 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:57 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:58 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:58 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:58 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:59 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:08:59 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:08:59 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:08:59 ifi-gory-stream mpd[8847]: Feb 22 17:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 17:09:00 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:00 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:00 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:00 ifi-gory-stream volumiologrotate[456]: ls: cannot access '/var/log/samba/log.wb-GORY': No such file or directory Feb 22 17:09:00 ifi-gory-stream volumiologrotate[456]: ls: cannot access 'STREAM': No such file or directory Feb 22 17:09:00 ifi-gory-stream systemd[1]: Starting Internet Detect for iFi Streamer... Feb 22 17:09:00 ifi-gory-stream systemd[1]: ifi-system-internet.service: Succeeded. Feb 22 17:09:00 ifi-gory-stream systemd[1]: Started Internet Detect for iFi Streamer. Feb 22 17:09:01 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:01 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:01 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:01 ifi-gory-stream systemd[1]: Started Music Player Daemon. Feb 22 17:09:01 ifi-gory-stream sudo[8843]: pam_unix(sudo:session): session closed for user root Feb 22 17:09:01 ifi-gory-stream volumio[1136]: error: updateQueue error: null Feb 22 17:09:02 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:02 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:02 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:03 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:03 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:03 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: ++++--++++ callMethod: {"type":"controller","endpoint":"audio_interface/alsa_controller","method":"saveResamplingOpts","data":{"resampling":true,"resampling_target_bitdepth":{"value":"24","label":"24"},"resampling_target_samplerate":{"value":"192000","label":"192 kHz"},"resampling_quality":{"value":"very high","label":"Very high"}}} Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Feb 22 17:09:04 ifi-gory-stream sudo[8993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 17:09:04 ifi-gory-stream sudo[8993]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:09:04 ifi-gory-stream sudo[8993]: pam_unix(sudo:session): session closed for user root Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:04 ifi-gory-stream sudo[8995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Feb 22 17:09:04 ifi-gory-stream sudo[8995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 17:09:04 ifi-gory-stream systemd[1]: mpd.socket: Succeeded. Feb 22 17:09:04 ifi-gory-stream systemd[1]: Closed mpd.socket. Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: MPD Permissions set Feb 22 17:09:04 ifi-gory-stream systemd[1]: Stopping Music Player Daemon... Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 17:09:04 ifi-gory-stream systemd[1]: mpd.service: Succeeded. Feb 22 17:09:04 ifi-gory-stream systemd[1]: Stopped Music Player Daemon. Feb 22 17:09:04 ifi-gory-stream sudo[8995]: pam_unix(sudo:session): session closed for user root Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 17:09:04 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 17:09:04 ifi-gory-stream sudo[9009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Feb 22 17:09:04 ifi-gory-stream sudo[9009]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:09:04 ifi-gory-stream systemd[1]: Listening on mpd.socket. Feb 22 17:09:04 ifi-gory-stream systemd[1]: Starting Music Player Daemon... Feb 22 17:09:04 ifi-gory-stream sudo[9012]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 17:09:04 ifi-gory-stream sudo[9012]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 17:09:04 ifi-gory-stream sudo[9012]: pam_unix(sudo:session): session closed for user root Feb 22 17:09:05 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:05 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:05 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:06 ifi-gory-stream volumio[1136]: info: ++++--++++ getState Feb 22 17:09:06 ifi-gory-stream volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 22 17:09:06 ifi-gory-stream volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 22 17:09:06 ifi-gory-stream volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 17:09:06 ifi-gory-stream volumio[1136]: TypeError: Cannot read property 'slice' of null Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at Object.onceWrapper (events.js:520:26) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at ClientRequest.emit (events.js:400:28) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at HTTPParser.execute () Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at TLSSocket.emit (events.js:400:28) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: at addChunk (internal/streams/readable.js:293:12) Feb 22 17:09:06 ifi-gory-stream volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 17:09:07 ifi-gory-stream mpd[9015]: Feb 22 17:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 17:09:07 ifi-gory-stream sudo[9054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-22 17:08 Feb 22 17:09:07 ifi-gory-stream sudo[9054]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="45aad196ab181636cec04ab79f52572f8ba3c549" VOLUMIO_FE_VERSION="8a7c1663e5becc06293ef92fd46324fc2cb9ce6e" VOLUMIO_FE3_VERSION="c5cad6acce062070373bc18affd0d2a1a9956a16" VOLUMIO_BE_VERSION="0fad662d4c47d7729fc677cd069fd5e675bebf9c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Nov 15 15:05:02 CST 2025" VOLUMIO_VERSION="3.00.6" VOLUMIO_HARDWARE="zen-stream" VOLUMIO_DEVICENAME="ZEN Stream" VOLUMIO_HASH="3350ed41a04d50b70f39bebb8e49f746"