-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2026-02-06 19:31:34 CET. -- Feb 06 19:30:01 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 06 19:30:01 volumio volumio[938]: info: In handleBrowseUri, curUri=spotify:artist:5Pnb9eXuGqSHlUsneXSdco Feb 06 19:30:03 volumio volumio[938]: info: Preload queue cleared Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:7ImfVR8cKSKQt3wplAXwY6 Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:79Wp36mBNSDNApmU0xmJS2 Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:5DVBUaOWIeW8Kejd3lfvii Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:5yQTZpUlrHZ3mIzeUPkVGT Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:3U9pdI5XTAVbSXpJbmZ0d3 Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:6KuGsJjLqZEvEDpjenjqDw Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:54I9LrI0RyRlDhF3iN7itf Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:1mOXLsfre1zBlewFAopzpt Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:5dspTGoFEa9wzEpxHKRVCi Feb 06 19:30:03 volumio volumio[938]: info: Preloading song: spotify:track:6xLTcoEllNOAvv8JAu8CWe Feb 06 19:30:03 volumio volumio[938]: info: Preload queue cleared Feb 06 19:30:03 volumio volumio[938]: info: Preload queue cleared Feb 06 19:30:04 volumio kernel: hwmon hwmon1: Voltage normalised Feb 06 19:30:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 06 19:30:11 volumio go-librespot[1280]: time="2026-02-06T19:30:11+01:00" level=trace msg="sent dealer ping" Feb 06 19:30:11 volumio go-librespot[1280]: time="2026-02-06T19:30:11+01:00" level=trace msg="received dealer pong" Feb 06 19:30:17 volumio kernel: hwmon hwmon1: Voltage normalised Feb 06 19:30:26 volumio ntpd[764]: 89.161.47.132 local addr 192.168.1.200 -> Feb 06 19:30:26 volumio ntpd[764]: 89.161.47.136 local addr 192.168.1.200 -> Feb 06 19:30:29 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 06 19:30:29 volumio volumio[938]: info: In handleBrowseUri, curUri=spotify:album:2x9LRmJ42QAbnU1Hp07UM1 Feb 06 19:30:30 volumio volumio[938]: info: Preload queue cleared Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:1yO6KhyPQ7SdGQvBiDZmzO Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:5dspTGoFEa9wzEpxHKRVCi Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:6aOHWO0xom15mX70INCtkh Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:5f5wVeLHL0TiG2hHKwDZVN Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:7IAED2mcWFDGDGkTcEymni Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:31cVyq3GtVNrtD41gETrON Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:0DgXUvHR4Zil8Xrvau1u3p Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:5g5hM4ExBQpT5VHyER8uiG Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:05C5JsRVLvPkzY2hCBS6dh Feb 06 19:30:30 volumio volumio[938]: info: Preloading song: spotify:track:79Wp36mBNSDNApmU0xmJS2 Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:1yO6KhyPQ7SdGQvBiDZmzO in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:1yO6KhyPQ7SdGQvBiDZmzO Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:5dspTGoFEa9wzEpxHKRVCi in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:5dspTGoFEa9wzEpxHKRVCi Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:6aOHWO0xom15mX70INCtkh in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:6aOHWO0xom15mX70INCtkh Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:5f5wVeLHL0TiG2hHKwDZVN in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:5f5wVeLHL0TiG2hHKwDZVN Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:7IAED2mcWFDGDGkTcEymni in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:7IAED2mcWFDGDGkTcEymni Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:31cVyq3GtVNrtD41gETrON in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:31cVyq3GtVNrtD41gETrON Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:0DgXUvHR4Zil8Xrvau1u3p in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:0DgXUvHR4Zil8Xrvau1u3p Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:5g5hM4ExBQpT5VHyER8uiG in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:5g5hM4ExBQpT5VHyER8uiG Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:05C5JsRVLvPkzY2hCBS6dh in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:05C5JsRVLvPkzY2hCBS6dh Feb 06 19:30:30 volumio volumio[938]: info: Exploding uri spotify:track:79Wp36mBNSDNApmU0xmJS2 in service spop Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:79Wp36mBNSDNApmU0xmJS2 Feb 06 19:30:30 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1yO6KhyPQ7SdGQvBiDZmzO","service":"spop","name":"Video Dotyk / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6aOHWO0xom15mX70INCtkh","service":"spop","name":"Dziękuję, Nie Tańczę / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":256,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5dspTGoFEa9wzEpxHKRVCi","service":"spop","name":"Hej, Man! / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5f5wVeLHL0TiG2hHKwDZVN","service":"spop","name":"Stan Pogody / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7IAED2mcWFDGDGkTcEymni","service":"spop","name":"Niech spadną gwiazdy / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":301,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:31cVyq3GtVNrtD41gETrON","service":"spop","name":"Życzenie / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":262,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DgXUvHR4Zil8Xrvau1u3p","service":"spop","name":"To Obojętność / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":312,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:05C5JsRVLvPkzY2hCBS6dh","service":"spop","name":"Diamentowy kolczyk / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5g5hM4ExBQpT5VHyER8uiG","service":"spop","name":"Przenikam / 21","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:31 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79Wp36mBNSDNApmU0xmJS2","service":"spop","name":"Stan Pogody / 21 - Skytech Remix","artist":"Anna Jurksztowicz","album":"Dziękuję, Nie Tańczę / 21 – Live","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c40901a27824696c5f7fea3","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:30:41 volumio go-librespot[1280]: time="2026-02-06T19:30:41+01:00" level=trace msg="sent dealer ping" Feb 06 19:30:41 volumio go-librespot[1280]: time="2026-02-06T19:30:41+01:00" level=trace msg="received dealer pong" Feb 06 19:31:09 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 06 19:31:09 volumio volumio[938]: info: In handleBrowseUri, curUri=spotify:album:53scdmbBAhAmBinH4rx3ED Feb 06 19:31:10 volumio volumio[938]: info: Preload queue cleared Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:59nOLZzUC5srLmpwd4b2Jg Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:5DVBUaOWIeW8Kejd3lfvii Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:2zpiOLSomhazOZzO5RTNO3 Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:7ImfVR8cKSKQt3wplAXwY6 Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:6jWVk8FUOP2nqMJmjoy4X0 Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:2mBqRe16ulepVBQRnmMFZW Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:23yX4kKtxtUd8jW75KUPYn Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:1gchzeFpgqm6Pr1cfR5XmN Feb 06 19:31:10 volumio volumio[938]: info: Preloading song: spotify:track:5d5EBDTT7RuBCF63Y8jpN5 Feb 06 19:31:10 volumio volumio[938]: info: Exploding uri spotify:track:59nOLZzUC5srLmpwd4b2Jg in service spop Feb 06 19:31:10 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:59nOLZzUC5srLmpwd4b2Jg Feb 06 19:31:10 volumio volumio[938]: info: Exploding uri spotify:track:5DVBUaOWIeW8Kejd3lfvii in service spop Feb 06 19:31:10 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:5DVBUaOWIeW8Kejd3lfvii Feb 06 19:31:10 volumio volumio[938]: info: Exploding uri spotify:track:2zpiOLSomhazOZzO5RTNO3 in service spop Feb 06 19:31:10 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:2zpiOLSomhazOZzO5RTNO3 Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:7ImfVR8cKSKQt3wplAXwY6 in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:7ImfVR8cKSKQt3wplAXwY6 Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:6jWVk8FUOP2nqMJmjoy4X0 in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:6jWVk8FUOP2nqMJmjoy4X0 Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:2mBqRe16ulepVBQRnmMFZW in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:2mBqRe16ulepVBQRnmMFZW Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:23yX4kKtxtUd8jW75KUPYn in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:23yX4kKtxtUd8jW75KUPYn Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:1gchzeFpgqm6Pr1cfR5XmN in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:1gchzeFpgqm6Pr1cfR5XmN Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","service":"spop","name":"Video dotyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: info: Exploding uri spotify:track:5d5EBDTT7RuBCF63Y8jpN5 in service spop Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: EXPLODING URI:spotify:track:5d5EBDTT7RuBCF63Y8jpN5 Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DVBUaOWIeW8Kejd3lfvii","service":"spop","name":"Hej, man!","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ImfVR8cKSKQt3wplAXwY6","service":"spop","name":"Stan pogody","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2zpiOLSomhazOZzO5RTNO3","service":"spop","name":"Dziękuję nie tańczę","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6jWVk8FUOP2nqMJmjoy4X0","service":"spop","name":"Niech spadną gwiazdy","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio go-librespot[1280]: time="2026-02-06T19:31:11+01:00" level=trace msg="sent dealer ping" Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2mBqRe16ulepVBQRnmMFZW","service":"spop","name":"Życzenie","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1gchzeFpgqm6Pr1cfR5XmN","service":"spop","name":"Przenikam","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio go-librespot[1280]: time="2026-02-06T19:31:11+01:00" level=trace msg="received dealer pong" Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:23yX4kKtxtUd8jW75KUPYn","service":"spop","name":"To obojętność","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":304,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:11 volumio volumio[938]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5d5EBDTT7RuBCF63Y8jpN5","service":"spop","name":"Diamentowy kolczyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273519567c2056c62a21da23d8f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 06 19:31:26 volumio volumio[938]: info: Preload queue cleared Feb 06 19:31:26 volumio volumio[938]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::ClearQueue Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::stop Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::clearPlayQueue Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::saveQueue Feb 06 19:31:26 volumio volumio[938]: info: CoreCommandRouter::volumioPushQueue Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::addQueueItems Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::addQueueItems Feb 06 19:31:26 volumio volumio[938]: info: Preload queue cleared Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:59nOLZzUC5srLmpwd4b2Jg Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:59nOLZzUC5srLmpwd4b2Jg Feb 06 19:31:26 volumio volumio[938]: info: CoreCommandRouter::volumioPushQueue Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::saveQueue Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::updateTrackBlock Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::getTrackBlock Feb 06 19:31:26 volumio volumio[938]: info: CoreCommandRouter::volumioPlay Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::play index 0 Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::addQueueItems Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::addQueueItems Feb 06 19:31:26 volumio volumio[938]: info: Preload queue cleared Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:5DVBUaOWIeW8Kejd3lfvii Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:5DVBUaOWIeW8Kejd3lfvii Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:2zpiOLSomhazOZzO5RTNO3 Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:2zpiOLSomhazOZzO5RTNO3 Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:7ImfVR8cKSKQt3wplAXwY6 Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:7ImfVR8cKSKQt3wplAXwY6 Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:6jWVk8FUOP2nqMJmjoy4X0 Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:6jWVk8FUOP2nqMJmjoy4X0 Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:2mBqRe16ulepVBQRnmMFZW Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:2mBqRe16ulepVBQRnmMFZW Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:23yX4kKtxtUd8jW75KUPYn Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:23yX4kKtxtUd8jW75KUPYn Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:1gchzeFpgqm6Pr1cfR5XmN Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:1gchzeFpgqm6Pr1cfR5XmN Feb 06 19:31:26 volumio volumio[938]: info: Adding Item to queue: spotify:track:5d5EBDTT7RuBCF63Y8jpN5 Feb 06 19:31:26 volumio volumio[938]: info: Using cached record of: spotify:track:5d5EBDTT7RuBCF63Y8jpN5 Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::stop Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:31:26 volumio volumio[938]: info: CoreCommandRouter::volumioPushQueue Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::saveQueue Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::play index undefined Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::updateTrackBlock Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::getTrackBlock Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:26 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer Feb 06 19:31:26 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:26 volumio volumio[938]: info: [1770402686841] ControllerSpotify::clearAddPlayTrack Feb 06 19:31:26 volumio volumio[938]: info: Sending Spotify command with payload to local API: /player/play Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="resolved context of track" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=trace msg="emitting websocket event: will_play" Feb 06 19:31:27 volumio volumio[938]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","play_origin":"go-librespot"}} Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2125" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="selected format OGG_VORBIS_160 (901050dbdffb0b018bd37961b1805e7f8be2bab6)" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=debug msg="requested aes key for file 901050dbdffb0b018bd37961b1805e7f8be2bab6, gid: 59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:27 volumio go-librespot[1280]: time="2026-02-06T19:31:27+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="fetched first chunk of 10, total size is 4827144 bytes" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="created new output device" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=info msg="loaded track \"Video dotyk\" (paused: false, position: 0ms, duration: 219226ms, prefetched: false)" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=trace msg="scheduling prefetch in 189s" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=trace msg="emitting websocket event: metadata" Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","name":"Video dotyk","artist_names":["Anna Jurksztowicz"],"album_name":"Dziękuję, nie tańczę","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02519567c2056c62a21da23d8f","position":0,"duration":219226,"release_date":"year:2003 month:9 day:30","track_number":1,"disc_number":1}} Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="fetched chunk 1/9, size: 524288" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 06 19:31:28 volumio go-librespot[1280]: time="2026-02-06T19:31:28+01:00" level=trace msg="emitting websocket event: playing" Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","resume":false,"play_origin":"go-librespot"}} Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: PUSH STATE SPOTIFY Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: {"status":"play","service":"spop","title":"Video dotyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","albumart":"https://i.scdn.co/image/ab67616d00001e02519567c2056c62a21da23d8f","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 06 19:31:28 volumio volumio[938]: info: CoreCommandRouter::servicePushState Feb 06 19:31:28 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:28 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Video dotyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","albumart":"https://i.scdn.co/image/ab67616d00001e02519567c2056c62a21da23d8f","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 06 19:31:28 volumio volumio[938]: verbose: CURRENT POSITION 0 Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::syncState stateService play Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus stop Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: PUSH STATE SPOTIFY Feb 06 19:31:28 volumio volumio[938]: SPOTIFY: {"status":"play","service":"spop","title":"Video dotyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","albumart":"https://i.scdn.co/image/ab67616d00001e02519567c2056c62a21da23d8f","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 06 19:31:28 volumio volumio[938]: info: CoreCommandRouter::servicePushState Feb 06 19:31:28 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:28 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Video dotyk","artist":"Anna Jurksztowicz","album":"Dziękuję, nie tańczę","albumart":"https://i.scdn.co/image/ab67616d00001e02519567c2056c62a21da23d8f","uri":"spotify:track:59nOLZzUC5srLmpwd4b2Jg","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 06 19:31:28 volumio volumio[938]: verbose: CURRENT POSITION 0 Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::syncState stateService play Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:31:28 volumio volumio[938]: info: Received an update from plugin. extracting info from payload Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::pushState Feb 06 19:31:28 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:28 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:31:28 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Feb 06 19:31:28 volumio volumio[938]: info: CoreStateMachine::pushState Feb 06 19:31:28 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 06 19:31:28 volumio volumio[938]: info: CoreCommandRouter::volumioPushState Feb 06 19:31:29 volumio go-librespot[1280]: time="2026-02-06T19:31:29+01:00" level=debug msg="fetched chunk 2/9, size: 524288" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:29 volumio go-librespot[1280]: time="2026-02-06T19:31:29+01:00" level=debug msg="fetched chunk 3/9, size: 524288" uri="spotify:track:59nOLZzUC5srLmpwd4b2Jg" Feb 06 19:31:34 volumio volumio[938]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Feb 06 19:31:34 volumio volumio[938]: info: PLUGIN onShutdown : networkfs Feb 06 19:31:34 volumio volumio[938]: info: PLUGIN onShutdown : audiophonicsonoff Feb 06 19:31:34 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:31:34 volumio volumio[938]: TypeError: Cannot read property 'writeSync' of undefined Feb 06 19:31:34 volumio volumio[938]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Feb 06 19:31:34 volumio volumio[938]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Feb 06 19:31:34 volumio volumio[938]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Feb 06 19:31:34 volumio volumio[938]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 06 19:31:34 volumio volumio[938]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 06 19:31:34 volumio volumio[938]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Feb 06 19:31:34 volumio volumio[938]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Feb 06 19:31:34 volumio volumio[938]: at ControllerAudiophonicsOnOff.hardShutdownRequest (/data/plugins/system_hardware/audiophonicsonoff/index.js:209:21) Feb 06 19:31:34 volumio volumio[938]: at /data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:135:9 Feb 06 19:31:34 volumio volumio[938]: at Array.forEach () Feb 06 19:31:34 volumio volumio[938]: at pollerEventHandler (/data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:134:32) Feb 06 19:31:34 volumio volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:31:34 volumio sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-06 19:30 Feb 06 19:31:34 volumio sudo[3092]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"