Mar 25 16:04:14 musicbox2 go-librespot[1642]: time="2026-03-25T16:04:14+01:00" level=trace msg="sent dealer ping" Mar 25 16:04:14 musicbox2 go-librespot[1642]: time="2026-03-25T16:04:14+01:00" level=trace msg="received dealer pong" Mar 25 16:04:44 musicbox2 volumio[1287]: info: Discovery: A device disappeared from network Mar 25 16:04:44 musicbox2 volumio[1287]: info: Discovery: Device musicbox3 disappeared from network Mar 25 16:04:44 musicbox2 go-librespot[1642]: time="2026-03-25T16:04:44+01:00" level=trace msg="sent dealer ping" Mar 25 16:04:44 musicbox2 go-librespot[1642]: time="2026-03-25T16:04:44+01:00" level=trace msg="received dealer pong" Mar 25 16:04:48 musicbox2 volumio[1287]: info: Discovery: adding 7a7224f9-0cf4-43ca-b034-d4edb9178309 Mar 25 16:04:48 musicbox2 volumio[1287]: info: Discovery: Found device Musicbox3 Mar 25 16:04:48 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetState Mar 25 16:04:54 musicbox2 sudo[4939]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 16:04:54 musicbox2 sudo[4939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 16:04:54 musicbox2 sudo[4939]: pam_unix(sudo:session): session closed for user root Mar 25 16:04:54 musicbox2 sudo[4941]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 16:04:54 musicbox2 sudo[4941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 16:04:54 musicbox2 sudo[4941]: pam_unix(sudo:session): session closed for user root Mar 25 16:04:54 musicbox2 volumio[1287]: verbose: New Socket.io Connection to 192.168.178.46 from 192.168.178.12 UA: Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 25 16:04:54 musicbox2 sudo[4945]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 16:04:54 musicbox2 sudo[4945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 16:04:54 musicbox2 sudo[4945]: pam_unix(sudo:session): session closed for user root Mar 25 16:04:54 musicbox2 sudo[4947]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 16:04:54 musicbox2 sudo[4947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 16:04:54 musicbox2 sudo[4947]: pam_unix(sudo:session): session closed for user root Mar 25 16:04:54 musicbox2 volumio[1287]: verbose: New Socket.io Connection to 192.168.178.46 from 192.168.178.12 UA: Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 25 16:04:54 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetState Mar 25 16:04:54 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 16:04:54 musicbox2 volumio[1287]: info: Listing playlists Mar 25 16:04:54 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 16:04:54 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 16:04:54 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 16:04:55 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 16:04:56 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 16:04:56 musicbox2 volumio[1287]: info: Received Get System Info Mar 25 16:04:56 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 16:04:56 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 16:04:56 musicbox2 volumio[1287]: info: Discovery: Getting this device information Mar 25 16:04:56 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetState Mar 25 16:04:56 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetQueue Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreStateMachine::getQueue Mar 25 16:04:58 musicbox2 volumio[1287]: info: CorePlayQueue::getQueue Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 16:04:58 musicbox2 volumio[1287]: info: Received Get System Info Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 16:04:58 musicbox2 volumio[1287]: info: Discovery: Getting this device information Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetState Mar 25 16:04:58 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 16:05:01 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 25 16:05:01 musicbox2 volumio[1287]: info: In handleBrowseUri, curUri=spotify Mar 25 16:05:01 musicbox2 volumio[1287]: info: New access token = BQCa07MWSWcHhH0dVUkMggbYIKz-5kHn4v0eI28oDIOv_dAjGwJTH2KZEb75HZ5Cs-SqP7TWExho76XRmLg8C0I6ZWHNozAcNsXKlnCL8TWGy8HqKfTALYoluqIySGaWE72KgIKMFFyBTlhG2Nt-xJE6K-Ng6PR1yVRcwfHUS9tfFJHRY8SSkYCQTgHNy_92gYsooI4deyBFC-rBrgmkXV6VFAUrZFk7qN1eDGETFnoB1efd7U6DKc1WxEhOrGmvV6QWhUtEA67Dwp_YERuWLf05pVpyeBNuj1PBOax2ZC17PMQ5QE02ZzvXNg Mar 25 16:05:01 musicbox2 volumio[1287]: info: New access token = BQDFPHCozZeort6eYGBAYmeUzEfsto-5f794moUP6gr26xPZUesEJEUyrVBhuH1UWSktBC9C38cG9HBWBNwc6L_vSy8VayVX1Wh57h-MZNe3hmFADKE2uhYYrhQ5w-0__m9YRBDrVcks7XUYA8rZTUM8I4Gfp1yGY3QX8t67denoz-33k4NXFs2p_D7HzBSvNSI53miuK8epsUpIXHZT-ZEYsRnq1IzAhHKfx5GHYgAhFB6PGUE1baJy0Rr11C7hZN0bv5NtP-SVgIWFN2COnhhOnzxlJ-i29Yq_phauZaktHQcmkXXjM49_lw Mar 25 16:05:01 musicbox2 volumio[1287]: info: New access token = BQCn9YJBZqjJxcnQRCQCC2d2l85wk_sYVkuPBHbvvrNsjo_-oEK6eAczgLJmTtsTJaOMXbKYSCK_BI9RLq5W9Ql3SI4qPUUOjshZ8YOtlfj0ex6zxVAAw3oD5PoJfziKBXWMej_2IiRF70Ek4iOcT325YjozxC3VSFoCGUuHJ6brA-D8KjFuwmoufiTJmE6tB0M0enI_RC1mCO5Yu-Jt9eNzocv-iUHGW5QH9XNk-ymdxwRfUtUz5-l6q57wa9I3ttQAHp0YRsYkfeG0cmLNagRKjzYcpb1MhPM6RYJFvXDc_euMRgtirqURUw Mar 25 16:05:02 musicbox2 volumio[1287]: info: Preload queue cleared Mar 25 16:05:02 musicbox2 volumio[1287]: info: Preload queue cleared Mar 25 16:05:02 musicbox2 volumio[1287]: info: Preload queue cleared Mar 25 16:05:02 musicbox2 volumio[1287]: info: Preload queue cleared Mar 25 16:05:07 musicbox2 volumio[1287]: Searching plugin music_service/spop Mar 25 16:05:07 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 25 16:05:08 musicbox2 volumio[1287]: info: All search sources collected, pushing search results Mar 25 16:05:11 musicbox2 volumio[1287]: Searching plugin music_service/spop Mar 25 16:05:11 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 25 16:05:12 musicbox2 volumio[1287]: info: All search sources collected, pushing search results Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 16:05:12 musicbox2 volumio[1287]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 25 16:05:12 musicbox2 volumio[1287]: info: Received Get System Version Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 16:05:12 musicbox2 volumio[1287]: info: Received Get System Info Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 16:05:12 musicbox2 volumio[1287]: info: Discovery: Getting this device information Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioGetState Mar 25 16:05:12 musicbox2 volumio[1287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 16:05:14 musicbox2 go-librespot[1642]: time="2026-03-25T16:05:14+01:00" level=trace msg="sent dealer ping" Mar 25 16:05:14 musicbox2 go-librespot[1642]: time="2026-03-25T16:05:14+01:00" level=trace msg="received dealer pong" Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreStateMachine::ClearQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreStateMachine::stop Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreStateMachine::serviceStop Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreCommandRouter::serviceStop Mar 25 16:05:22 musicbox2 volumio[1287]: error: WARNING: No stop method for service squeezelite_mc Mar 25 16:05:22 musicbox2 volumio[1287]: info: CorePlayQueue::clearPlayQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CorePlayQueue::saveQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioPushQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreStateMachine::addQueueItems Mar 25 16:05:22 musicbox2 volumio[1287]: info: CorePlayQueue::addQueueItems Mar 25 16:05:22 musicbox2 volumio[1287]: info: Preload queue cleared Mar 25 16:05:22 musicbox2 volumio[1287]: info: Adding Item to queue: spotify:playlist:738vMaJEelkx9LIrNiBMNM Mar 25 16:05:22 musicbox2 volumio[1287]: info: Exploding uri spotify:playlist:738vMaJEelkx9LIrNiBMNM in service spop Mar 25 16:05:22 musicbox2 volumio[1287]: SPOTIFY: EXPLODING URI:spotify:playlist:738vMaJEelkx9LIrNiBMNM Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioPushQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CorePlayQueue::saveQueue Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreStateMachine::updateTrackBlock Mar 25 16:05:22 musicbox2 volumio[1287]: info: CorePlayQueue::getTrackBlock Mar 25 16:05:22 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioPlay Mar 25 16:05:22 musicbox2 volumio[1287]: verbose: UNSET VOLATILE: Service: squeezelite_mc Mar 25 16:05:27 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioVolatilePlay Mar 25 16:05:27 musicbox2 volumio[1287]: info: CoreStateMachine::volatilePlay Mar 25 16:05:27 musicbox2 volumio[1287]: info: WARNING: No play method for volatile plugin squeezelite_mc Mar 25 16:05:31 musicbox2 go-librespot[1642]: time="2026-03-25T16:05:31+01:00" level=trace msg="received accesspoint ping" Mar 25 16:05:31 musicbox2 go-librespot[1642]: time="2026-03-25T16:05:31+01:00" level=trace msg="received accesspoint pong ack" Mar 25 16:05:31 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioVolatilePlay Mar 25 16:05:31 musicbox2 volumio[1287]: info: CoreStateMachine::volatilePlay Mar 25 16:05:31 musicbox2 volumio[1287]: info: WARNING: No play method for volatile plugin squeezelite_mc Mar 25 16:05:40 musicbox2 volumio[1287]: info: CoreCommandRouter::volumioPlay Mar 25 16:05:40 musicbox2 volumio[1287]: verbose: UNSET VOLATILE: Service: squeezelite_mc Mar 25 16:05:40 musicbox2 volumio[1287]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 16:05:40 musicbox2 volumio[1287]: TypeError: Cannot read properties of null (reading 'logger') Mar 25 16:05:40 musicbox2 volumio[1287]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86) Mar 25 16:05:40 musicbox2 volumio[1287]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_pushEmptyState (/data/plugins/music_service/squeezelite_mc/dist/index.js:1009:36) Mar 25 16:05:40 musicbox2 volumio[1287]: at ControllerSqueezeliteMC.onUnsetVolatile (/data/plugins/music_service/squeezelite_mc/dist/index.js:265:120) Mar 25 16:05:40 musicbox2 volumio[1287]: at CoreStateMachine.unSetVolatile (/volumio/app/statemachine.js:1555:27) Mar 25 16:05:40 musicbox2 volumio[1287]: at CoreCommandRouter.volumioPlay (/volumio/app/index.js:1406:21) Mar 25 16:05:40 musicbox2 volumio[1287]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:241:35) Mar 25 16:05:40 musicbox2 volumio[1287]: at Socket.emit (node:events:514:28) Mar 25 16:05:40 musicbox2 volumio[1287]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 25 16:05:40 musicbox2 volumio[1287]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 25 16:05:40 musicbox2 volumio[1287]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 16:05:42 musicbox2 sudo[5054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 16:04' Mar 25 16:05:42 musicbox2 sudo[5054]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"