May 25 15:45:00 volumio go-librespot[1607]: time="2026-05-25T15:45:00-03:00" level=trace msg="sent dealer ping" May 25 15:45:00 volumio go-librespot[1607]: time="2026-05-25T15:45:00-03:00" level=trace msg="received dealer pong" May 25 15:45:03 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 15:45:03 volumio volumio[1209]: info: CURURI: playlists May 25 15:45:03 volumio volumio[1209]: info: Listing playlists May 25 15:45:03 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:04 volumio ntpd[1027]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 15:45:04 volumio ntpd[1027]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 25 15:45:04 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.1 May 25 15:45:04 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.123 May 25 15:45:04 volumio ntpd[1027]: DNS: Pool skipping: 168.96.251.195 May 25 15:45:04 volumio ntpd[1027]: DNS: Pool skipping: 170.155.148.1 May 25 15:45:04 volumio ntpd[1027]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 25 15:45:05 volumio ntpd[1027]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 15:45:05 volumio ntpd[1027]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 168.96.251.195 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 170.155.148.1 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.1 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.123 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 2606:4700:f1::123 May 25 15:45:05 volumio ntpd[1027]: DNS: Pool skipping: 2606:4700:f1::1 May 25 15:45:05 volumio ntpd[1027]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 25 15:45:06 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 15:45:06 volumio volumio[1209]: info: CURURI: music-library May 25 15:45:06 volumio volumio[1209]: error: Failed LSINFO: null May 25 15:45:06 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:06 volumio ntpd[1027]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 15:45:06 volumio ntpd[1027]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 25 15:45:06 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.123 May 25 15:45:06 volumio ntpd[1027]: DNS: Pool skipping: 168.96.251.195 May 25 15:45:06 volumio ntpd[1027]: DNS: Pool skipping: 170.155.148.1 May 25 15:45:06 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.1 May 25 15:45:06 volumio ntpd[1027]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 25 15:45:07 volumio ntpd[1027]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 15:45:07 volumio ntpd[1027]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 25 15:45:07 volumio ntpd[1027]: DNS: Pool skipping: 168.96.251.195 May 25 15:45:07 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.123 May 25 15:45:07 volumio ntpd[1027]: DNS: Pool skipping: 170.155.148.1 May 25 15:45:07 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.1 May 25 15:45:07 volumio ntpd[1027]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 25 15:45:09 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 25 15:45:09 volumio volumio[1209]: info: In handleBrowseUri, curUri=spotify May 25 15:45:09 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:09 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:09 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:09 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:12 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 25 15:45:12 volumio volumio[1209]: info: In handleBrowseUri, curUri=spotify/playlists May 25 15:45:13 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:30 volumio go-librespot[1607]: time="2026-05-25T15:45:30-03:00" level=trace msg="sent dealer ping" May 25 15:45:30 volumio go-librespot[1607]: time="2026-05-25T15:45:30-03:00" level=trace msg="received dealer pong" May 25 15:45:37 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 25 15:45:37 volumio volumio[1209]: info: In handleBrowseUri, curUri=spotify/myalbums May 25 15:45:39 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:50 volumio volumio[1209]: Searching plugin music_service/spop May 25 15:45:50 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , search May 25 15:45:51 volumio volumio[1209]: Searching plugin music_service/spop May 25 15:45:51 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , search May 25 15:45:51 volumio volumio[1209]: info: All search sources collected, pushing search results May 25 15:45:52 volumio volumio[1209]: info: All search sources collected, pushing search results May 25 15:45:56 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 25 15:45:56 volumio volumio[1209]: info: In handleBrowseUri, curUri=spotify:artist:0ge5hip4BBZCjQ2srIW2nf May 25 15:45:58 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:4XOuRe3vuY9D4Ut51PRyF8 May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:5kQ4FnC2JafOa7n1XIjmKy May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:3jKllf2AkQCN9567aFCGoN May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:3GcFQ0CA6H05t5JS6CXtXl May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:7JNwSlRo6UFeOnYUd7YV0V May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:2aSU06uCBBCM46NcLWnhwx May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:2RbsY7xaires2E1eKjvuzz May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:1IRsv6aGmX08j2H7q3eVve May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:2SaLY6eSNwqomPyqHFErix May 25 15:45:58 volumio volumio[1209]: info: Preloading song: spotify:track:0jnnvEf0FDHJ7O0Sht4QHr May 25 15:45:58 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:58 volumio volumio[1209]: info: Preload queue cleared May 25 15:45:58 volumio volumio[1209]: info: No valid Plugin REST Endpoint May 25 15:46:00 volumio go-librespot[1607]: time="2026-05-25T15:46:00-03:00" level=trace msg="sent dealer ping" May 25 15:46:00 volumio go-librespot[1607]: time="2026-05-25T15:46:00-03:00" level=trace msg="received dealer pong" May 25 15:46:02 volumio volumio5-onboarding[1523]: time=2026-05-25T15:46:02.800-03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.105:58814 @ 0x2b92ae0" latency=671.194428ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 25 15:46:03 volumio sudo[2638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 15:46:03 volumio sudo[2638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 15:46:03 volumio sudo[2638]: pam_unix(sudo:session): session closed for user root May 25 15:46:03 volumio sudo[2640]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 15:46:03 volumio sudo[2640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 15:46:03 volumio sudo[2640]: pam_unix(sudo:session): session closed for user root May 25 15:46:03 volumio volumio[1209]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.105 UA: Mozilla/5.0 (Linux; Android 16; SM-X133 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 25 15:46:03 volumio sudo[2644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 15:46:04 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 15:46:04 volumio sudo[2644]: pam_unix(sudo:session): session closed for user root May 25 15:46:04 volumio sudo[2646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 15:46:04 volumio sudo[2646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 15:46:04 volumio sudo[2646]: pam_unix(sudo:session): session closed for user root May 25 15:46:04 volumio volumio[1209]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.105 UA: Mozilla/5.0 (Linux; Android 16; SM-X133 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::volumioGetVisibleSources May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::volumioGetState May 25 15:46:04 volumio volumio[1209]: info: CorePlayQueue::getTrack 0 May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::volumioGetQueue May 25 15:46:04 volumio volumio[1209]: info: CoreStateMachine::getQueue May 25 15:46:04 volumio volumio[1209]: info: CorePlayQueue::getQueue May 25 15:46:04 volumio volumio[1209]: info: Listing playlists May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 15:46:04 volumio volumio[1209]: info: Received Get System Info May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:46:04 volumio volumio[1209]: info: Discovery: Getting this device information May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::volumioGetState May 25 15:46:04 volumio volumio[1209]: info: CorePlayQueue::getTrack 0 May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::volumioGetState May 25 15:46:04 volumio volumio[1209]: info: CorePlayQueue::getTrack 0 May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 15:46:04 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 15:46:05 volumio volumio[1209]: info: Received Get System Info May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:46:05 volumio volumio[1209]: info: Discovery: Getting this device information May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::volumioGetState May 25 15:46:05 volumio volumio[1209]: info: CorePlayQueue::getTrack 0 May 25 15:46:05 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:46:07 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 15:46:07 volumio volumio[1209]: info: Received Get System Info May 25 15:46:07 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:46:07 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:46:07 volumio volumio[1209]: info: Discovery: Getting this device information May 25 15:46:07 volumio volumio[1209]: info: CoreCommandRouter::volumioGetState May 25 15:46:07 volumio volumio[1209]: info: CorePlayQueue::getTrack 0 May 25 15:46:07 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:46:08 volumio volumio[1209]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 25 15:46:08 volumio volumio[1209]: info: In handleBrowseUri, curUri=spotify:artist:3sFCutpYHXWB3j7tFSQRFR May 25 15:46:10 volumio volumio[1209]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:46:10 volumio volumio[1209]: TypeError: Cannot read properties of undefined (reading 'url') May 25 15:46:10 volumio volumio[1209]: at /data/plugins/music_service/spop/index.js:2446:60 May 25 15:46:10 volumio volumio[1209]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 15:46:10 volumio volumio[1209]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:46:10 volumio sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 15:45' May 25 15:46:10 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"