Nov 02 12:45:10 volumiohifi ntpd[979]: CLOCK: time stepped by 173425.691799 Nov 02 12:45:10 volumiohifi ntpd[979]: CLOCK: time changed from 2025-10-31 to 2025-11-02 Nov 02 12:45:10 volumiohifi ntpd[979]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Nov 02 12:45:10 volumiohifi systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Nov 02 12:45:10 volumiohifi systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Nov 02 12:45:10 volumiohifi systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Nov 02 12:45:10 volumiohifi systemd[1]: e2scrub_all.service: Deactivated successfully. Nov 02 12:45:10 volumiohifi systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Nov 02 12:45:10 volumiohifi systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Nov 02 12:45:10 volumiohifi systemd[1]: dpkg-db-backup.service: Deactivated successfully. Nov 02 12:45:10 volumiohifi systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Nov 02 12:45:10 volumiohifi volumio[1090]: info: Applying required configuration parameters for plugin touch_display Nov 02 12:45:11 volumiohifi volumio[1090]: info: Loading i18n strings for locale en Nov 02 12:45:12 volumiohifi volumio[1090]: Updating browse sources language Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::initPlayerControls Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:45:12 volumiohifi volumio[1090]: Express server listening on port 3000 Nov 02 12:45:12 volumiohifi volumio[1090]: [Metrics] WebUI: 13s 587.70ms Nov 02 12:45:12 volumiohifi volumio[1090]: info: CoreStateMachine::resetVolumioState Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreStateMachine::getcurrentVolume Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreStateMachine::pushState Nov 02 12:45:13 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioPushState Nov 02 12:45:13 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:45:13 volumiohifi volumio[1090]: info: Volumio Network Manager: Network status updated: 1 Nov 02 12:45:13 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 12:45:13 volumiohifi volumio[1090]: info: Reloading queue from file Nov 02 12:45:14 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreStateMachine::setRepeat null single undefined Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreStateMachine::pushState Nov 02 12:45:14 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioPushState Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreStateMachine::setRandom null Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreStateMachine::pushState Nov 02 12:45:14 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioPushState Nov 02 12:45:14 volumiohifi volumio[1090]: info: Setting Device type: Raspberry PI Nov 02 12:45:14 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 12:45:14 volumiohifi volumio[1090]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 02 12:45:14 volumiohifi volumio[1090]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Nov 02 12:45:14 volumiohifi volumio[1090]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 02 12:45:14 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: A device disappeared from network Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: adding f1d3ba33-0a83-4700-b2af-f61858b9fef5 Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: Found device VolumioHIFI Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:14 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:14 volumiohifi volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: A device disappeared from network Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: Device volumiohifi disappeared from network Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: adding f1d3ba33-0a83-4700-b2af-f61858b9fef5 Nov 02 12:45:14 volumiohifi volumio[1090]: info: Discovery: Found device VolumioHIFI Nov 02 12:45:14 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:14 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:14 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Nov 02 12:45:14 volumiohifi volumio[1090]: info: Completed loading Core Plugins Nov 02 12:45:14 volumiohifi volumio[1090]: info: Preparing to generate the ALSA configuration file Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: this is already registered, f1d3ba33-0a83-4700-b2af-f61858b9fef5 Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: Found device VolumioHIFI Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: this is already registered, f1d3ba33-0a83-4700-b2af-f61858b9fef5 Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: Found device VolumioHIFI Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1347]: Starting albumart workers Nov 02 12:45:15 volumiohifi volumio[1090]: info: Received Get System Info Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: Getting this device information Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:45:15 volumiohifi volumio[1346]: Starting albumart workers Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1345]: Starting albumart workers Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:45:15 volumiohifi volumio[1090]: info: Received Get System Info Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:45:15 volumiohifi volumio[1090]: info: Discovery: Getting this device information Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:15 volumiohifi volumio[1090]: info: Listing playlists Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetQueue Nov 02 12:45:15 volumiohifi volumio[1090]: info: CoreStateMachine::getQueue Nov 02 12:45:15 volumiohifi volumio[1090]: info: CorePlayQueue::getQueue Nov 02 12:45:16 volumiohifi volumio[1090]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:45:16 volumiohifi volumio[1090]: info: Output device has changed, restarting MPD Nov 02 12:45:16 volumiohifi volumio[1090]: info: ___________ START PLUGINS ___________ Nov 02 12:45:16 volumiohifi volumio[1090]: info: ControllerMpd::onStart: Initializing MPD Nov 02 12:45:16 volumiohifi volumio[1090]: info: Creating MPD Configuration file Nov 02 12:45:16 volumiohifi sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:45:16 volumiohifi sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:16 volumiohifi sudo[1417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:45:16 volumiohifi volumio[1090]: info: [1762080316353] CoreMusicLibrary::Adding element Last_100 Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:45:16 volumiohifi volumio[1090]: info: [1762080316354] CoreMusicLibrary::Adding element Webradio Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:45:16 volumiohifi sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:45:16 volumiohifi volumio[1090]: info: Initializing BBC Radios Nov 02 12:45:16 volumiohifi sudo[1418]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:16 volumiohifi sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:45:16 volumiohifi sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:45:16 volumiohifi sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:16 volumiohifi sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:16 volumiohifi sudo[1420]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:16 volumiohifi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 02 12:45:16 volumiohifi systemd[1]: Starting mpd.service - Music Player Daemon... Nov 02 12:45:16 volumiohifi sudo[1431]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:45:16 volumiohifi sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 02 12:45:16 volumiohifi sudo[1437]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 02 12:45:16 volumiohifi sudo[1431]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:16 volumiohifi systemd[1]: mpd.service: Deactivated successfully. Nov 02 12:45:16 volumiohifi systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 02 12:45:16 volumiohifi systemd[1]: mpd.socket: Deactivated successfully. Nov 02 12:45:16 volumiohifi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 02 12:45:16 volumiohifi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 02 12:45:16 volumiohifi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 02 12:45:16 volumiohifi systemd[1]: Starting mpd.service - Music Player Daemon... Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:45:16 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:45:16 volumiohifi sudo[1443]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:45:16 volumiohifi sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 02 12:45:16 volumiohifi sudo[1446]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 02 12:45:16 volumiohifi sudo[1443]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:17 volumiohifi volumio[1090]: info: [squeezelite_mc] Starting proxy server... Nov 02 12:45:17 volumiohifi volumio[1090]: info: Loading i18n strings for locale en Nov 02 12:45:17 volumiohifi volumio[1090]: info: Loading i18n strings for locale en Nov 02 12:45:17 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:45:17 volumiohifi volumio[1090]: info: Volumio Calling Home Nov 02 12:45:17 volumiohifi volumio[1090]: info: [squeezelite_mc] Proxy server started on port 40909 Nov 02 12:45:17 volumiohifi sudo[1452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 02 12:45:17 volumiohifi sudo[1452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:17 volumiohifi sudo[1454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 02 12:45:17 volumiohifi sudo[1454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:17 volumiohifi sudo[1452]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:18 volumiohifi sudo[1458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 02 12:45:18 volumiohifi sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:18 volumiohifi systemd[1]: Reloading. Nov 02 12:45:19 volumiohifi volumio-remote-updater[692]: [2025-11-02 12:45:19] [connect] Successful connection Nov 02 12:45:19 volumiohifi systemd[1]: systemd-fsckd.service: Deactivated successfully. Nov 02 12:45:19 volumiohifi systemd[1]: Reloading. Nov 02 12:45:19 volumiohifi sudo[1454]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:20 volumiohifi sudo[1458]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreStateMachine::pushState Nov 02 12:45:20 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioPushState Nov 02 12:45:20 volumiohifi volumio[1090]: info: [squeezelite_mc] Server discovery started Nov 02 12:45:20 volumiohifi volumio[1090]: info: [squeezelite_mc] Player finder started Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:20 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:45:20 volumiohifi volumio[1090]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 02 12:45:21 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: No backlight interface detected. Nov 02 12:45:21 volumiohifi volumio[1090]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE"] Nov 02 12:45:21 volumiohifi volumio[1090]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"VolumioHIFI","dsdFormat":null,"card":"5","mixerType":"None","mixer":null} Nov 02 12:45:21 volumiohifi volumio[1090]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: systemctl daemon-reload succeeded. Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 02 12:45:21 volumiohifi volumio[1090]: info: MPD Permissions set Nov 02 12:45:21 volumiohifi volumio[1090]: info: MPD Permissions set Nov 02 12:45:21 volumiohifi sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 02 12:45:21 volumiohifi sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:21 volumiohifi systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Nov 02 12:45:21 volumiohifi sudo[1521]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:21 volumiohifi volumio-remote-updater[692]: [2025-11-02 12:45:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1762080319 101 Nov 02 12:45:21 volumiohifi volumio[1090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Nov 02 12:45:21 volumiohifi volumio[1090]: info: Volumio called home Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 02 12:45:21 volumiohifi volumio[1090]: info: touch_display: Volumio Kiosk started. Nov 02 12:45:21 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioGetState Nov 02 12:45:21 volumiohifi volumio[1090]: info: CorePlayQueue::getTrack 0 Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi sudo[1519]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 02 12:45:22 volumiohifi sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 02 12:45:22 volumiohifi sudo[1519]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi startx[1554]: X.Org X Server 1.21.1.7 Nov 02 12:45:22 volumiohifi startx[1554]: X Protocol Version 11, Revision 0 Nov 02 12:45:22 volumiohifi mpd[1447]: 2025-11-02T12:45:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:45:22 volumiohifi startx[1554]: Current Operating System: Linux volumiohifi 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Nov 02 12:45:22 volumiohifi startx[1554]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=480 bcm2708_fb.fbheight=320 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=D8:3A:DD:D2:1B:93 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=d81a33e0-3252-4fe8-924e-4ef7d21a2af4 imgfile=/volumio_current.sqsh bootpart=UUID=19DB-8D40 datapart=UUID=6e7e8450-b3e1-44b1-8b42-98ba71899af8 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Nov 02 12:45:22 volumiohifi startx[1554]: xorg-server 2:21.1.7-3+rpt3+deb12u10 (https://www.debian.org/support) Nov 02 12:45:22 volumiohifi startx[1554]: Current version of pixman: 0.44.0 Nov 02 12:45:22 volumiohifi startx[1554]: Before reporting problems, check http://wiki.x.org Nov 02 12:45:22 volumiohifi startx[1554]: to make sure that you have the latest version. Nov 02 12:45:22 volumiohifi startx[1554]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 02 12:45:22 volumiohifi startx[1554]: (++) from command line, (!!) notice, (II) informational, Nov 02 12:45:22 volumiohifi startx[1554]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 02 12:45:22 volumiohifi startx[1554]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Nov 2 12:45:22 2025 Nov 02 12:45:22 volumiohifi startx[1554]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 02 12:45:22 volumiohifi startx[1554]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:45:22 volumiohifi volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:45:22 volumiohifi systemd[1]: Started mpd.service - Music Player Daemon. Nov 02 12:45:22 volumiohifi sudo[1417]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:22 volumiohifi sudo[1423]: pam_unix(sudo:session): session closed for user root Nov 02 12:45:22 volumiohifi volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 02 12:45:22 volumiohifi startx[1562]: modprobe: FATAL: Module g2d_23 not found in directory /lib/modules/6.12.47-v7l+ Nov 02 12:45:23 volumiohifi volumio[1090]: info: Preload queue cleared Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreStateMachine::ClearQueue Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreStateMachine::stop Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:45:23 volumiohifi volumio[1090]: info: CorePlayQueue::clearPlayQueue Nov 02 12:45:23 volumiohifi volumio[1090]: info: CorePlayQueue::saveQueue Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreCommandRouter::volumioPushQueue Nov 02 12:45:23 volumiohifi volumio[1090]: info: CoreStateMachine::addQueueItems Nov 02 12:45:23 volumiohifi volumio[1090]: info: CorePlayQueue::addQueueItems Nov 02 12:45:23 volumiohifi volumio[1090]: info: Preload queue cleared Nov 02 12:45:23 volumiohifi volumio[1090]: info: Adding Item to queue: albums://Adele/Greatest%20Hits Nov 02 12:45:23 volumiohifi volumio[1090]: info: Exploding uri albums://Adele/Greatest%20Hits in service mpd Nov 02 12:45:23 volumiohifi volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:45:23 volumiohifi volumio[1090]: TypeError: Cannot read properties of undefined (reading 'sendCommand') Nov 02 12:45:23 volumiohifi volumio[1090]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2139:20) Nov 02 12:45:23 volumiohifi volumio[1090]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Nov 02 12:45:23 volumiohifi volumio[1090]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Nov 02 12:45:23 volumiohifi volumio[1090]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Nov 02 12:45:23 volumiohifi volumio[1090]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Nov 02 12:45:23 volumiohifi volumio[1090]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Nov 02 12:45:23 volumiohifi volumio[1090]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:526:23) Nov 02 12:45:23 volumiohifi volumio[1090]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Nov 02 12:45:23 volumiohifi volumio[1090]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Nov 02 12:45:23 volumiohifi volumio[1090]: at Socket.emit (node:events:514:28) Nov 02 12:45:23 volumiohifi volumio[1090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:45:23 volumiohifi systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 02 12:45:25 volumiohifi sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-02 12:44' Nov 02 12:45:25 volumiohifi sudo[1660]: 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"