-- Logs begin at Sun 2025-06-08 18:00:27 CEST, end at Sun 2025-06-08 18:06:22 CEST. -- Jun 08 18:05:41 volumio volumio[861]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.86 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 08 18:05:41 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:05:41 volumio volumio[861]: info: CoreCommandRouter::volumioGetQueue Jun 08 18:05:41 volumio volumio[861]: info: CoreStateMachine::getQueue Jun 08 18:05:41 volumio volumio[861]: info: CorePlayQueue::getQueue Jun 08 18:05:41 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 08 18:05:41 volumio volumio[861]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 08 18:05:41 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 08 18:05:41 volumio volumio[861]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 08 18:05:41 volumio volumio[861]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 08 18:05:41 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 08 18:05:45 volumio volumio[861]: info: CALLMETHOD: system_controller system setTestSystem true Jun 08 18:05:45 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem Jun 08 18:05:45 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates Jun 08 18:05:45 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 18:05:45 volumio volumio[861]: info: Device is now in test mode Jun 08 18:05:46 volumio volumio-remote-updater[612]: Test mode enabled Jun 08 18:05:46 volumio volumio-remote-updater[612]: No alpha test mode Jun 08 18:05:46 volumio volumio[861]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 08 18:05:46 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 08 18:05:47 volumio volumio[861]: info: CALLMETHOD: system_controller system setTestSystem false Jun 08 18:05:47 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem Jun 08 18:05:47 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates Jun 08 18:05:47 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 18:05:47 volumio volumio[861]: info: Test File deleted Jun 08 18:05:47 volumio volumio-remote-updater[612]: No test mode Jun 08 18:05:47 volumio volumio-remote-updater[612]: No alpha test mode Jun 08 18:05:47 volumio volumio[861]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 08 18:05:47 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 08 18:05:49 volumio volumio[861]: info: CALLMETHOD: system_controller system setTestPlugins true Jun 08 18:05:49 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Jun 08 18:05:49 volumio volumio[861]: info: Plugins store is now in test mode Jun 08 18:05:50 volumio volumio[861]: info: CALLMETHOD: system_controller system enableSSH true Jun 08 18:05:50 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Jun 08 18:05:50 volumio sudo[2642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Jun 08 18:05:50 volumio sudo[2642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:05:50 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jun 08 18:05:50 volumio sshd[2645]: Server listening on 0.0.0.0 port 22. Jun 08 18:05:50 volumio sshd[2645]: Server listening on :: port 22. Jun 08 18:05:50 volumio systemd[1]: Started OpenBSD Secure Shell server. Jun 08 18:05:50 volumio sudo[2642]: pam_unix(sudo:session): session closed for user root Jun 08 18:05:50 volumio sudo[2646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Jun 08 18:05:50 volumio sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:05:50 volumio systemd[1]: Reloading. Jun 08 18:05:51 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 08 18:05:51 volumio systemd[1]: Reloading. Jun 08 18:05:51 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 08 18:05:51 volumio systemd[1]: Reloading. Jun 08 18:05:51 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 08 18:05:51 volumio sudo[2646]: pam_unix(sudo:session): session closed for user root Jun 08 18:05:51 volumio volumio[861]: info: enable SSH service success Jun 08 18:05:53 volumio nmbd[766]: [2025/06/08 18:05:53.024367, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 08 18:05:53 volumio nmbd[766]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.52 for name WORKGROUP<1d>. Jun 08 18:05:53 volumio nmbd[766]: This response was from IP 192.168.1.86, reporting an IP address of 192.168.1.86. Jun 08 18:06:01 volumio sudo[2714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 18:06:01 volumio sudo[2714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:06:01 volumio sudo[2714]: pam_unix(sudo:session): session closed for user root Jun 08 18:06:01 volumio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 18:06:01 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:06:01 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root Jun 08 18:06:01 volumio volumio[861]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.86 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 08 18:06:02 volumio sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 18:06:02 volumio sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:06:02 volumio sudo[2722]: pam_unix(sudo:session): session closed for user root Jun 08 18:06:02 volumio sudo[2725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 18:06:02 volumio sudo[2725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 18:06:02 volumio sudo[2725]: pam_unix(sudo:session): session closed for user root Jun 08 18:06:02 volumio volumio[861]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.86 UA: Mozilla/5.0 (X11; Linux x86_64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 18:06:02 volumio volumio[861]: info: Received Get System Info Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 18:06:02 volumio volumio[861]: info: Discovery: Getting this device information Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:02 volumio volumio[861]: info: Listing playlists Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 18:06:02 volumio volumio[861]: info: [squeezelite_mc] Proxy request for Musique, URL: http://192.168.1.3:9000/imageproxy/https%3A%2F%2Fe-cdns-images.dzcdn.net%2Fimages%2Fcover%2Fe575520f28958e17a38da890bc322257%2F500x500-000000-80-0-0.jpg/image.jpg Jun 08 18:06:02 volumio volumio[861]: error: [squeezelite_mc] Proxy: invalid URL (http%3A%2F%2F192.168.1.3%3A9000%2Fimageproxy%2Fhttps%253A%252F%252Fe-cdns-images.dzcdn.net%252Fimages%252Fcover%252Fe575520f28958e17a38da890bc322257%252F500x500-000000-80-0-0.jpg%2Fimage.jpg) Jun 08 18:06:02 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 18:06:03 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 18:06:04 volumio volumio[861]: info: Received Get System Info Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 18:06:04 volumio volumio[861]: info: Discovery: Getting this device information Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:04 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 18:06:05 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 18:06:05 volumio volumio[861]: info: Received Get System Info Jun 08 18:06:05 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 18:06:05 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 18:06:05 volumio volumio[861]: info: Discovery: Getting this device information Jun 08 18:06:05 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:05 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 18:06:06 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 18:06:06 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 18:06:11 volumio volumio[861]: info: Starting Uninstall of plugin user_interface - now_playing Jun 08 18:06:11 volumio volumio[861]: info: Uninstalling plugin now_playing Jun 08 18:06:12 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 18:06:20 volumio volumio[861]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 08 18:06:20 volumio volumio[861]: error: Failed callmethod call: TypeError: Cannot read property 'broadcastMessage' of null Jun 08 18:06:20 volumio volumio[861]: info: Received Get System Version Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 18:06:20 volumio volumio[861]: info: Received Get System Info Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 18:06:20 volumio volumio[861]: info: Discovery: Getting this device information Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::volumioGetState Jun 08 18:06:20 volumio volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 18:06:21 volumio ntpd[1040]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 08 18:06:22 volumio volumio[861]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/buster/amd64 Jun 08 18:06:22 volumio volumio[861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 18:06:22 volumio volumio[861]: TypeError: Cannot read property 'broadcastMessage' of null Jun 08 18:06:22 volumio volumio[861]: at /volumio/app/index.js:1534:32 Jun 08 18:06:22 volumio volumio[861]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jun 08 18:06:22 volumio volumio[861]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jun 08 18:06:22 volumio volumio[861]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Jun 08 18:06:22 volumio volumio[861]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Jun 08 18:06:22 volumio volumio[861]: at PluginManager.updatePlugin (/volumio/app/pluginmanager.js:938:8) Jun 08 18:06:22 volumio volumio[861]: at CoreCommandRouter.updatePlugin (/volumio/app/index.js:1467:22) Jun 08 18:06:22 volumio volumio[861]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1235:45) Jun 08 18:06:22 volumio volumio[861]: at Socket.emit (events.js:400:28) Jun 08 18:06:22 volumio volumio[861]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 08 18:06:22 volumio volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 08 18:06:22 volumio volumio[861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 18:06:22 volumio sudo[2770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 18:05 Jun 08 18:06:22 volumio sudo[2770]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"