Oct 28 20:18:07 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:07 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:07 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:07 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:07 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:07 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:08 leovol volumio[1275]: verbose: New Socket.io Connection to 192.168.1.41:3000 from 192.168.1.38 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 28 20:18:08 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:08 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:08 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 20:18:08 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:10 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:10 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:10 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:10 leovol sudo[18250]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 20:18:10 leovol sudo[18250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 20:18:10 leovol sudo[18250]: pam_unix(sudo:session): session closed for user root Oct 28 20:18:10 leovol sudo[18252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 20:18:10 leovol sudo[18252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 20:18:10 leovol sudo[18252]: pam_unix(sudo:session): session closed for user root Oct 28 20:18:10 leovol volumio[1275]: verbose: New Socket.io Connection to 192.168.1.41 from 192.168.1.38 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 28 20:18:10 leovol sudo[18257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 20:18:10 leovol sudo[18257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 20:18:10 leovol sudo[18257]: pam_unix(sudo:session): session closed for user root Oct 28 20:18:10 leovol sudo[18259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 20:18:10 leovol sudo[18259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 20:18:10 leovol sudo[18259]: pam_unix(sudo:session): session closed for user root Oct 28 20:18:10 leovol volumio[1275]: verbose: New Socket.io Connection to 192.168.1.41 from 192.168.1.38 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Oct 28 20:18:10 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:11 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 20:18:11 leovol volumio[1275]: info: Received Get System Info Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:11 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:11 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:11 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:11 leovol volumio[1275]: info: Listing playlists Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 20:18:11 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::volumioGetQueue Oct 28 20:18:12 leovol volumio[1275]: info: CoreStateMachine::getQueue Oct 28 20:18:12 leovol volumio[1275]: info: CorePlayQueue::getQueue Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 20:18:12 leovol volumio[1275]: info: Received Get System Info Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:12 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:12 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:12 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:14 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 20:18:14 leovol volumio[1275]: info: Received Get System Info Oct 28 20:18:14 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:14 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:14 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:14 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:14 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:14 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:18 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 28 20:18:18 leovol volumio[1275]: info: In handleBrowseUri, curUri=spotify Oct 28 20:18:18 leovol volumio[1275]: info: New access token = BQBr6f1jcHTzpDkrVdsNIcpSlamqAsOg4SQWZ4Wj6aQKYBsIDgielyXaApA-TvdlJUxuFAQZXffkjmCVuOLmZEW0bgnLyUSNgp4QmJuA_2HbbkByNkPDtdBurz32FYGFbJZEmBZVPsX7y_HhIv5OB2lBCCVzW1wMWCHLNiRoZiPLMVuZuGMdedF0TL9TKREEje3ZsqMrRZ843b6LuGay_lY1GcJi6jxuht3Az6gfJZIqQKLC2DyS3gH4GxPlB3vHWB-eIvS20I6_spfV_lnXxqKzxRHg2Zg_SPIwkYTccmObKRtHxL_FFFSFL_BJSTZG9v-n0aaignJJuad8Nk4QwJXLhg7DYm5HCPEhSmC94-NBWtvvPMd3_S7QGR925_o9Iizw Oct 28 20:18:19 leovol volumio[1275]: info: New access token = BQCWkZCaYmab59oLdgxGbjkIkyzfLbPR1Plap7FHN5Pgy8Gxl0JgmkPTbDiEvaWBiRciLBejgMOUfzPJ4kUcrpuS51nfhutudA7qCXJ6RdsoRIXL92i8u4MTnfhdjUpGmAkyynuODHcsE38zwBk4--8xeotNXBEzkvUX85jMspLTY1LMqwlaURK9AzYxYgllVYmIvSISW8W0tl75dONAz6klB1Eyqu503Hq7mI81Fmzz-9I6S0CnJP69owscDdwjZs4BEzT3tyrrAoWs0796hgxyGdCtUeYOnUr0zLrwNo68Vt_WfR0mWxlC2m2Hn5KR_Bc4Z8a4vqRMdTG3KFToL63HFcVLuGLHOzMNPTEx4gTMMK9vlPJ8PCM6CwGowswb2pvu Oct 28 20:18:19 leovol volumio[1275]: info: New access token = BQCmPOQEZU6hFm2HnyRH8A9UYULkH0QZ0-y1MaV-2DweP6wfDYWpQjwooDQ8NHWCyR8Y6vqnzl-LZTAtWMLX0FEUBVyrdcFNZo6w9UsKECoKBhbI3KP3hIhwJnsCzwV8TNp-pXZoMeoH0wcVXrK4ccI7LxbsuTySK1iJfiD-HTP7yn4zLGUJQ5Nu3MdkCm77-cIM9pSj_6MOk0fuMWZQDnRuDl9fhA494ncgE8s0LaQCepepxgYVU5sz_V4WfkmR_W4XLCZKtHWGbEWjByMIdozN83H1cLPqFMIYMZ1Ob4Pm17aovsUROz-cnt4rN8o-bRf2HX0ihsaxtzUYpQB-6U_nDuG8-fuZrsGG_ghk-OINbuEuO8x1iaWNwwk2r7Wzb8RD Oct 28 20:18:19 leovol volumio[1275]: info: Preload queue cleared Oct 28 20:18:19 leovol volumio[1275]: info: Preload queue cleared Oct 28 20:18:19 leovol volumio[1275]: info: Preload queue cleared Oct 28 20:18:19 leovol volumio[1275]: info: Preload queue cleared Oct 28 20:18:20 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 28 20:18:23 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:23 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:23 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:23 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:23 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:24 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:25 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:25 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:26 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:27 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:27 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:28 leovol go-librespot[1643]: time="2025-10-28T20:18:28+07:00" level=trace msg="sent dealer ping" Oct 28 20:18:28 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:28 leovol go-librespot[1643]: time="2025-10-28T20:18:28+07:00" level=trace msg="received dealer pong" Oct 28 20:18:29 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 20:18:29 leovol volumio[1275]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 20:18:29 leovol volumio[1275]: info: Received Get System Version Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 20:18:29 leovol volumio[1275]: info: Received Get System Info Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:29 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:29 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:29 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:30 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:31 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:31 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:32 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:32 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 20:18:32 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 20:18:32 leovol volumio[1275]: info: Discovery: Getting this device information Oct 28 20:18:32 leovol volumio[1275]: info: CoreCommandRouter::volumioGetState Oct 28 20:18:32 leovol volumio[1275]: info: CorePlayQueue::getTrack 0 Oct 28 20:18:32 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 20:18:34 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:34 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:34 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:44 leovol volumio[1275]: Searching plugin music_service/spop Oct 28 20:18:44 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 28 20:18:44 leovol volumio[1275]: info: All search sources collected, pushing search results Oct 28 20:18:58 leovol go-librespot[1643]: time="2025-10-28T20:18:58+07:00" level=trace msg="sent dealer ping" Oct 28 20:18:58 leovol go-librespot[1643]: time="2025-10-28T20:18:58+07:00" level=trace msg="received dealer pong" Oct 28 20:19:01 leovol volumio[1275]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 28 20:19:01 leovol volumio[1275]: info: In handleBrowseUri, curUri=spotify:artist:26sEMvmYstuVoelDe5LL3v Oct 28 20:19:02 leovol volumio[1275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 20:19:02 leovol volumio[1275]: TypeError: Cannot read properties of undefined (reading 'url') Oct 28 20:19:02 leovol volumio[1275]: at /data/plugins/music_service/spop/index.js:2449:60 Oct 28 20:19:02 leovol volumio[1275]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 28 20:19:02 leovol volumio[1275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 20:19:02 leovol sudo[18378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 20:18' Oct 28 20:19:02 leovol sudo[18378]: 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"