-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Tue 2025-07-01 00:17:35 +07. -- Jul 01 00:16:42 volumio9 systemd-timedated[994]: Changed local time to Tue Jul 1 00:16:42 2025 Jul 01 00:16:42 volumio9 systemd[1]: Starting Daily man-db regeneration... Jul 01 00:16:42 volumio9 sudo[992]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:42 volumio9 volumio-time-update[599]: volumio-time-update-util: System time updated successfully. Jul 01 00:16:42 volumio9 systemd[1]: Starting Daily apt download activities... Jul 01 00:16:42 volumio9 systemd[1]: Started Volumio Time Update Utility. Jul 01 00:16:42 volumio9 volumio[820]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 01 00:16:42 volumio9 volumio[820]: info: Reloading queue from file Jul 01 00:16:42 volumio9 volumio[820]: info: Setting Device type: Raspberry PI Jul 01 00:16:42 volumio9 volumio[820]: info: CoreStateMachine::setRepeat null single undefined Jul 01 00:16:42 volumio9 volumio[820]: info: CoreStateMachine::pushState Jul 01 00:16:42 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::volumioPushState Jul 01 00:16:42 volumio9 volumio[820]: info: CoreStateMachine::setRandom null Jul 01 00:16:42 volumio9 volumio[820]: info: CoreStateMachine::pushState Jul 01 00:16:42 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::volumioPushState Jul 01 00:16:42 volumio9 volumio[820]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 01 00:16:42 volumio9 volumio[820]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jul 01 00:16:42 volumio9 volumio[820]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 01 00:16:42 volumio9 volumio[820]: info: Discovery: A device disappeared from network Jul 01 00:16:42 volumio9 volumio[820]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 01 00:16:42 volumio9 volumio[820]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 01 00:16:42 volumio9 volumio[820]: info: Completed loading Core Plugins Jul 01 00:16:42 volumio9 volumio[820]: info: Preparing to generate the ALSA configuration file Jul 01 00:16:42 volumio9 volumio[820]: info: Asound.conf file unchanged, so no further update is needed Jul 01 00:16:42 volumio9 volumio[820]: info: Output device has changed, restarting MPD Jul 01 00:16:42 volumio9 sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 00:16:42 volumio9 sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 volumio[820]: info: Output device has changed, restarting Shairport Sync Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:42 volumio9 sudo[1039]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:42 volumio9 sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 00:16:42 volumio9 sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 systemd[1]: Stopping Music Player Daemon... Jul 01 00:16:42 volumio9 volumio[820]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 00:16:42 volumio9 volumio[820]: info: ___________ START PLUGINS ___________ Jul 01 00:16:42 volumio9 volumio[820]: info: ControllerMpd::onStart: Initializing MPD Jul 01 00:16:42 volumio9 volumio[820]: info: Creating MPD Configuration file Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 00:16:42 volumio9 volumio[820]: info: [1751303802434] CoreMusicLibrary::Adding element Media Servers Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:16:42 volumio9 sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 00:16:42 volumio9 sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:42 volumio9 sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:42 volumio9 sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 sudo[1056]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:42 volumio9 volumio[820]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:42 volumio9 volumio[820]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 00:16:42 volumio9 volumio[820]: info: [1751303802549] CoreMusicLibrary::Adding element Last_100 Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 00:16:42 volumio9 volumio[820]: info: [1751303802551] CoreMusicLibrary::Adding element Webradio Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:16:42 volumio9 volumio[820]: info: Initializing BBC Radios Jul 01 00:16:42 volumio9 systemd[1]: mpd.service: Succeeded. Jul 01 00:16:42 volumio9 systemd[1]: Stopped Music Player Daemon. Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:42 volumio9 volumio[820]: info: Creating Spotify config file Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:42 volumio9 systemd[1]: Starting Music Player Daemon... Jul 01 00:16:42 volumio9 volumio[820]: info: Loading i18n strings for locale th Jul 01 00:16:42 volumio9 volumio[820]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 01 00:16:42 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:16:42 volumio9 volumio[820]: info: Volumio Calling Home Jul 01 00:16:42 volumio9 sudo[1071]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 01 00:16:42 volumio9 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 sudo[1071]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:42 volumio9 sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 01 00:16:42 volumio9 sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 01 00:16:42 volumio9 sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 01 00:16:42 volumio9 sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:42 volumio9 volumio[820]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 01 00:16:42 volumio9 volumio[820]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 01 00:16:42 volumio9 volumio[820]: info: MPD Permissions set Jul 01 00:16:42 volumio9 volumio[820]: info: MPD Permissions set Jul 01 00:16:42 volumio9 systemd[1]: Reloading. Jul 01 00:16:42 volumio9 volumio[820]: info: Spotify config file written Jul 01 00:16:42 volumio9 nmbd[747]: [2025/07/01 00:16:42.911294, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 01 00:16:42 volumio9 nmbd[747]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 01 00:16:42 volumio9 sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 01 00:16:42 volumio9 sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 volumio[820]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:16:43 volumio9 volumio[820]: info: No need to fix Spotify hosts Jul 01 00:16:43 volumio9 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. Jul 01 00:16:43 volumio9 volumio[820]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 01 00:16:43 volumio9 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 01 00:16:43 volumio9 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 volumio[820]: info: Starting Shairport Sync Jul 01 00:16:43 volumio9 volumio[820]: info: Starting Shairport Sync Jul 01 00:16:43 volumio9 volumio[820]: info: Starting Shairport Sync Jul 01 00:16:43 volumio9 sudo[1123]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:43 volumio9 sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 00:16:43 volumio9 sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:16:43 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:43 volumio9 sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 00:16:43 volumio9 sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 volumio[820]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 01 00:16:43 volumio9 volumio[820]: info: Volumio called home Jul 01 00:16:43 volumio9 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 00:16:43 volumio9 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 volumio[820]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 01 00:16:43 volumio9 volumio[820]: SPOTIFY: BQBPX1D5ko8kBAE2UdrslvJRyXANpXqHSQI2AB-rGdcGcPE8dByFpZNw9ECykSCH6AX_A4yxH9R9bkyR3pvaqkfL2Q35tQTZ5Z5f6j8du8F7m8CwB01sQcr5k_ZgSzfht8KwGh-Q8sxTgAHAb9_DkYngBgpvzHAZM5FRLuAA15J8hjJ2AeDbgWq7mp2viLD3lghlIvrT94jiZT0ZXLyDEprY9ySEcKF8zwAfxj4LBPOZtLxUzUtYMBV6_TmJRdy7l1XfMxZA9O-q8ClAZW029FN4VWHU1RF_c7K7hlg9-VLFO5pK1pU Jul 01 00:16:43 volumio9 volumio[820]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 01 00:16:43 volumio9 volumio[820]: info: New Spotify access token = BQBPX1D5ko8kBAE2UdrslvJRyXANpXqHSQI2AB-rGdcGcPE8dByFpZNw9ECykSCH6AX_A4yxH9R9bkyR3pvaqkfL2Q35tQTZ5Z5f6j8du8F7m8CwB01sQcr5k_ZgSzfht8KwGh-Q8sxTgAHAb9_DkYngBgpvzHAZM5FRLuAA15J8hjJ2AeDbgWq7mp2viLD3lghlIvrT94jiZT0ZXLyDEprY9ySEcKF8zwAfxj4LBPOZtLxUzUtYMBV6_TmJRdy7l1XfMxZA9O-q8ClAZW029FN4VWHU1RF_c7K7hlg9-VLFO5pK1pU Jul 01 00:16:43 volumio9 volumio[820]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 01 00:16:43 volumio9 systemd[1]: Started Samba NMB Daemon. Jul 01 00:16:43 volumio9 sudo[1093]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:43 volumio9 sudo[1088]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:43 volumio9 volumio[820]: info: touch_display: systemctl daemon-reload succeeded. Jul 01 00:16:43 volumio9 systemd[1]: Reloading. Jul 01 00:16:43 volumio9 volumio[820]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 01 00:16:43 volumio9 sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 01 00:16:43 volumio9 sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:43 volumio9 mpd[1085]: Jul 01 00:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 01 00:16:43 volumio9 volumio[820]: SPOTIFY: User informations: {"country":"TH","display_name":"ชนะ ชนะภัย","email":"schanapai@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ujwh6hkh6kshabxeokjduu7j3e"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ujwh6hkh6kshabxeokjduu7j3e","id":"31ujwh6hkh6kshabxeokjduu7j3e","images":[{"height":300,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_dst-jpg_s240x240_tt6&_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=lOWGnB7hDxgQ7kNvwFBNsV6&_nc_oc=Admu5V-TCdb0nz5P8-C1iASUjVkAXnK2in3DNDdCrFJeuFK4IkKfbeka02-AdukvJwcldoEQd2bUqoMUJ-JUkz1w&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=OIyzD_xi9m0P2hzRnkKvXQ&oh=00_AfMKwsW5FMlBye-hE1XQxKCq0s3tJDce63fXlzdPJYBuAg&oe=68683335","width":300},{"height":64,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=lOWGnB7hDxgQ7kNvwFBNsV6&_nc_oc=Admu5V-TCdb0nz5P8-C1iASUjVkAXnK2in3DNDdCrFJeuFK4IkKfbeka02-AdukvJwcldoEQd2bUqoMUJ-JUkz1w&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=OIyzD_xi9m0P2hzRnkKvXQ&oh=00_AfMgWrJTJ4KuVNytdQqkJiFraGGskYwVvfaePlZQi9tTbw&oe=68683335","width":64}],"product":"premium","type":"user","uri":"spotify:user:31ujwh6hkh6kshabxeokjduu7j3e"} Jul 01 00:16:43 volumio9 volumio[820]: info: Spotify Successfully logged in Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 01 00:16:43 volumio9 volumio[820]: info: [1751303803721] CoreMusicLibrary::Adding element Spotify Jul 01 00:16:43 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:16:43 volumio9 volumio[820]: Cannot find translation for source Spotify Jul 01 00:16:43 volumio9 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. Jul 01 00:16:44 volumio9 systemd[1]: Started Music Player Daemon. Jul 01 00:16:44 volumio9 sudo[1090]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 sudo[1043]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 01 00:16:44 volumio9 sudo[1058]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 volumio[820]: error: MPD error: The expression evaluated to a falsy value: Jul 01 00:16:44 volumio9 volumio[820]: assert.ok(self.idling) Jul 01 00:16:44 volumio9 volumio[820]: error: The expression evaluated to a falsy value: Jul 01 00:16:44 volumio9 volumio[820]: assert.ok(self.idling) Jul 01 00:16:44 volumio9 volumio[820]: error: updateQueue error: null Jul 01 00:16:44 volumio9 volumio[820]: info: MPD running with PID1085 Jul 01 00:16:44 volumio9 volumio[820]: ,establishing connection Jul 01 00:16:44 volumio9 volumio[820]: error: updateQueue error: null Jul 01 00:16:44 volumio9 sudo[1135]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 systemd[1]: Started Volumio Kiosk. Jul 01 00:16:44 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: Volumio Kiosk started. Jul 01 00:16:44 volumio9 systemd[1]: Started go-librespot Daemon. Jul 01 00:16:44 volumio9 sudo[1097]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 systemd[1]: Starting Samba Winbind Daemon... Jul 01 00:16:44 volumio9 go-librespot[1155]: go-librespot daemon starting... Jul 01 00:16:44 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jul 01 00:16:44 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 01 00:16:44 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:16:44 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:44 volumio9 volumio[820]: info: Completed starting Core Plugins Jul 01 00:16:44 volumio9 volumio[820]: info: ------------------------------------------- Jul 01 00:16:44 volumio9 volumio[820]: info: ----- MyVolumio plugins startup ---- Jul 01 00:16:44 volumio9 volumio[820]: info: ------------------------------------------- Jul 01 00:16:44 volumio9 volumio[820]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 01 00:16:44 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:16:44 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:44 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 01 00:16:44 volumio9 sudo[1126]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 sudo[1128]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 sudo[1131]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:44 volumio9 volumio[820]: info: Shairport-Sync Started Jul 01 00:16:44 volumio9 volumio[820]: Error adding Membership: Error: addMembership EINVAL Jul 01 00:16:44 volumio9 volumio[820]: info: Shairport-Sync Started Jul 01 00:16:44 volumio9 volumio[820]: info: Shairport-Sync Started Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: Jul 01 00:16:44 volumio9 winbindd[1157]: [2025/07/01 00:16:44.767649, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 01 00:16:44 volumio9 winbindd[1157]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 01 00:16:44 volumio9 winbindd[1157]: [2025/07/01 00:16:44.867179, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 01 00:16:44 volumio9 systemd[1]: Started Samba Winbind Daemon. Jul 01 00:16:44 volumio9 winbindd[1157]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: 0 Jul 01 00:16:44 volumio9 systemd[1]: Starting Samba SMB Daemon... Jul 01 00:16:44 volumio9 volumio[820]: info: touch_display: X display number found: 0 Jul 01 00:16:45 volumio9 volumio[820]: info: touch_display: X display number found: 0 Jul 01 00:16:45 volumio9 systemd[1]: apt-daily.service: Succeeded. Jul 01 00:16:45 volumio9 systemd[1]: Started Daily apt download activities. Jul 01 00:16:45 volumio9 volumio[820]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 01 00:16:45 volumio9 startx[1154]: X.Org X Server 1.20.4 Jul 01 00:16:45 volumio9 startx[1154]: X Protocol Version 11, Revision 0 Jul 01 00:16:45 volumio9 startx[1154]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 01 00:16:45 volumio9 startx[1154]: Current Operating System: Linux volumio9 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jul 01 00:16:45 volumio9 startx[1154]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:68:34:85 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=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=e88ce58b-e81a-40e3-8391-6dece4f7098e 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 Jul 01 00:16:45 volumio9 startx[1154]: Build Date: 04 April 2023 07:50:56AM Jul 01 00:16:45 volumio9 startx[1154]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 01 00:16:45 volumio9 startx[1154]: Current version of pixman: 0.36.0 Jul 01 00:16:45 volumio9 startx[1154]: Before reporting problems, check http://wiki.x.org Jul 01 00:16:45 volumio9 startx[1154]: to make sure that you have the latest version. Jul 01 00:16:45 volumio9 startx[1154]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 01 00:16:45 volumio9 startx[1154]: (++) from command line, (!!) notice, (II) informational, Jul 01 00:16:45 volumio9 startx[1154]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 01 00:16:45 volumio9 startx[1154]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jul 1 00:16:45 2025 Jul 01 00:16:45 volumio9 startx[1154]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 01 00:16:45 volumio9 startx[1154]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 01 00:16:45 volumio9 systemd[1]: Starting Daily apt upgrade and clean activities... Jul 01 00:16:45 volumio9 volumio[820]: info: touch_display: X display number found: 0 Jul 01 00:16:45 volumio9 go-librespot[1155]: time="2025-07-01T00:16:45+07:00" level=info msg="running go-librespot 0.2.0" Jul 01 00:16:45 volumio9 go-librespot[1155]: time="2025-07-01T00:16:45+07:00" level=debug msg="app state loaded" Jul 01 00:16:45 volumio9 go-librespot[1155]: time="2025-07-01T00:16:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 01 00:16:45 volumio9 systemd[1]: Started Samba SMB Daemon. Jul 01 00:16:45 volumio9 systemd[1]: Reached target Multi-User System. Jul 01 00:16:45 volumio9 smbd[1224]: [2025/07/01 00:16:45.705948, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 01 00:16:45 volumio9 systemd[1]: Reached target Graphical Interface. Jul 01 00:16:45 volumio9 smbd[1224]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 01 00:16:45 volumio9 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 01 00:16:45 volumio9 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 01 00:16:45 volumio9 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 01 00:16:47 volumio9 sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Music Jul 01 00:16:47 volumio9 sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:47 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 01 00:16:47 volumio9 systemd[1]: man-db.service: Succeeded. Jul 01 00:16:47 volumio9 systemd[1]: Started Daily man-db regeneration. Jul 01 00:16:47 volumio9 kernel: cryptd: max_cpu_qlen set to 1000 Jul 01 00:16:47 volumio9 volumio[820]: info: go-librespot daemon successfully initialized Jul 01 00:16:47 volumio9 systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 01 00:16:47 volumio9 systemd[1]: Started Daily apt upgrade and clean activities. Jul 01 00:16:47 volumio9 systemd[1]: Startup finished in 17.299s (kernel) + 23.111s (userspace) = 40.410s. Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=info msg="zeroconf server listening on port 33095" Jul 01 00:16:47 volumio9 sudo[1272]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="obtained new client token: AABEw4Ttg52To4VVlL8QLqA4KHojTitmgcJzh5t9gchTn+zoDHVr/1EgoZP6TEbildmORkkxsmArVRNoLZ0vkm0EhaWfFWebaG0n8mjx7CMXfl11GLtOMBhSO1FK44TGB/2DBQpI8qaJZFCNTEHKnw4lmpTrpKZoALG7QcoPGuIXHry2/bqmF9CvmYPMH0B00dSh6Ispl5bniX8J1KmwZRKq+zHUKTX/sVCVSB8APiR0ZsAPHR90" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="completed keyexchange" Jul 01 00:16:47 volumio9 go-librespot[1155]: time="2025-07-01T00:16:47+07:00" level=debug msg="completed challenge" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=info msg="authenticated AP as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=info msg="authenticated Login5 as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="initializing zeroconf session, username: 31ujwh6hkh6kshabxeokjduu7j3e" Jul 01 00:16:48 volumio9 volumio[820]: info: Volumio Network Manager: Network status updated: 1 Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="dealer connection opened" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=trace msg="starting accesspoint recv loop" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=trace msg="starting dealer recv loop" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=trace msg="received accesspoint ping" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="received connection id: MDRkY2M4YjMtMGYwMC00YzI0LWIzZGYtNTdkNjM4YmQzYWFhK2RlYWxlcit0Y3A6Ly8wYWIxNTliYi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMjYzMTlCNzdDQUU5QkFGRDM2MkQzQTZCQzc1RkQyMUZDN0UxMEY5NkQ1N0MzNTg1NTIyRkFFNjQxODZFNDMzMw==" Jul 01 00:16:48 volumio9 sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 00:16:48 volumio9 sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:48 volumio9 sudo[1352]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:48 volumio9 sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 00:16:48 volumio9 sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:48 volumio9 sudo[1354]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:48 volumio9 sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 01 00:16:48 volumio9 sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=trace msg="received accesspoint pong ack" Jul 01 00:16:48 volumio9 volumio-remote-updater[609]: [2025-07-01 00:16:48] [connect] Successful connection Jul 01 00:16:48 volumio9 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 01 00:16:48 volumio9 volumio-remote-updater[609]: [2025-07-01 00:16:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751303808 101 Jul 01 00:16:48 volumio9 sudo[1359]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:48 volumio9 volumio[820]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jul 01 00:16:48 volumio9 volumio[820]: info: Upmpdcli Daemon Started Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="put connect state because NEW_DEVICE" Jul 01 00:16:48 volumio9 go-librespot[1155]: time="2025-07-01T00:16:48+07:00" level=debug msg="update volume to 65535/65535" Jul 01 00:16:48 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:16:48 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:48 volumio9 volumio[820]: info: touch_display: Setting screensaver timeout to 45 seconds. Jul 01 00:16:48 volumio9 volumio[1362]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 01 00:16:49 volumio9 volumio[820]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 01 00:16:49 volumio9 go-librespot[1155]: time="2025-07-01T00:16:49+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 01 00:16:49 volumio9 go-librespot[1155]: time="2025-07-01T00:16:49+07:00" level=trace msg="emitting websocket event: volume" Jul 01 00:16:50 volumio9 volumio[820]: info: Initializing connection to go-librespot Websocket Jul 01 00:16:50 volumio9 go-librespot[1155]: time="2025-07-01T00:16:50+07:00" level=debug msg="new websocket client" Jul 01 00:16:50 volumio9 volumio[820]: info: Connection to go-librespot Websocket established Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin bluetooth to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin multiroom to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin metavolumio to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin cd_controller to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 01 00:16:53 volumio9 volumio[820]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 01 00:16:54 volumio9 volumio[820]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 01 00:16:54 volumio9 volumio[820]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 01 00:16:54 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:54 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:16:54 volumio9 volumio[820]: info: Starting MyVolumio Remote Streaming Endpoints Jul 01 00:16:54 volumio9 volumio[820]: info: MyVolumio login type: Token Jul 01 00:16:54 volumio9 volumio[820]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 01 00:16:54 volumio9 volumio[820]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 01 00:16:55 volumio9 volumio[820]: info: Starting Streaming Service Transparent Proxy Jul 01 00:16:55 volumio9 volumio[820]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 01 00:16:55 volumio9 volumio[820]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 01 00:16:55 volumio9 volumio[820]: info: Streaming services startup Jul 01 00:16:55 volumio9 volumio[820]: info: Starting Streaming Daemon Jul 01 00:16:55 volumio9 sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 01 00:16:55 volumio9 sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:16:55 volumio9 volumio[820]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 01 00:16:55 volumio9 sudo[1493]: pam_unix(sudo:session): session closed for user root Jul 01 00:16:55 volumio9 volumio[820]: info: Getting Spotify volume Jul 01 00:16:55 volumio9 volumio[820]: error: Cannot start Volumio Streaming Daemon Jul 01 00:16:55 volumio9 volumio[820]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 01 00:16:55 volumio9 volumio[820]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 01 00:16:56 volumio9 volumio[820]: info: Spotify volume: 100 Jul 01 00:16:56 volumio9 volumio[820]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 01 00:16:56 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:16:56 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:16:56 volumio9 volumio[820]: error: MyVolumio Custom Token format not valid, refreshing it Jul 01 00:16:56 volumio9 volumio[820]: STREAMING PROXY: Starting server on port 3245 Jul 01 00:16:56 volumio9 volumio[820]: Node JS runtime: 14 Jul 01 00:16:57 volumio9 volumio[820]: info: MyVolumio login type: Token Jul 01 00:16:57 volumio9 volumio[820]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 01 00:16:59 volumio9 systemd[1]: systemd-fsckd.service: Succeeded. Jul 01 00:17:02 volumio9 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 01 00:17:03 volumio9 volumio[1362]: .....................................................................................................................................................................++++ Jul 01 00:17:03 volumio9 volumio[820]: info: MyVolumio token set successfully Jul 01 00:17:03 volumio9 volumio[820]: info: MYVOLUMIO: Adding device Jul 01 00:17:03 volumio9 volumio[820]: info: MYVOLUMIO: Evaluating Server Jul 01 00:17:04 volumio9 volumio[820]: info: MyVolumio status changed Jul 01 00:17:04 volumio9 volumio[820]: info: Streaming services startup Jul 01 00:17:04 volumio9 volumio[820]: info: Starting Streaming Daemon Jul 01 00:17:04 volumio9 volumio[820]: info: Removing browser output: myVolumio user plan is not superstar Jul 01 00:17:04 volumio9 volumio[820]: info: Removing audio output: Jul 01 00:17:04 volumio9 volumio[820]: info: Stoppping Tunnel 1 Jul 01 00:17:04 volumio9 sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 01 00:17:04 volumio9 sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:04 volumio9 sudo[1573]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:04 volumio9 volumio[820]: error: Cannot start Volumio Streaming Daemon Jul 01 00:17:04 volumio9 volumio[820]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 01 00:17:04 volumio9 volumio[820]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 01 00:17:04 volumio9 sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 01 00:17:04 volumio9 sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:04 volumio9 sudo[1576]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:04 volumio9 volumio[820]: info: Remote SSH Stopped Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:04 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:04 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:04 volumio9 volumio[820]: verbose: New Socket.io Connection to 192.168.1.83:3000 from 192.168.1.74 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:04 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 01 00:17:04 volumio9 volumio[820]: info: Setting Geolocation for MyVolumio to as1 Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:04 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:05 volumio9 volumio[820]: info: Updating MyVolumio device info Jul 01 00:17:05 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:05 volumio9 volumio[820]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 01 00:17:06 volumio9 volumio[820]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 01 00:17:07 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:07 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:08 volumio9 volumio[820]: verbose: New Socket.io Connection to 192.168.1.83:3000 from 192.168.1.74 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 01 00:17:08 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:08 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:08 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 01 00:17:08 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 01 00:17:08 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:08 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:08 volumio9 volumio[820]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 01 00:17:08 volumio9 sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 00:17:08 volumio9 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:08 volumio9 sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 00:17:08 volumio9 sudo[1628]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:09 volumio9 sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:09 volumio9 sudo[1630]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:09 volumio9 volumio[820]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 01 00:17:09 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 00:17:09 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 01 00:17:09 volumio9 volumio[820]: info: MYVOLUMIO: Adding device Jul 01 00:17:09 volumio9 volumio[820]: info: MYVOLUMIO: Evaluating Server Jul 01 00:17:09 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 01 00:17:09 volumio9 volumio[820]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 01 00:17:09 volumio9 volumio[820]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 01 00:17:09 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:09 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetVisibleSources Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:10 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 01 00:17:10 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:10 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:10 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:10 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:10 volumio9 volumio[820]: info: Listing playlists Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 01 00:17:10 volumio9 volumio[820]: info: Setting Geolocation for MyVolumio to as1 Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:10 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 01 00:17:11 volumio9 volumio[820]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 01 00:17:11 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:11 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:11 volumio9 volumio[820]: info: Updating MyVolumio device info Jul 01 00:17:11 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:12 volumio9 systemd[1]: systemd-timedated.service: Succeeded. Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:12 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:12 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:12 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:12 volumio9 volumio[820]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 00:17:12 volumio9 volumio-remote-updater[609]: No test mode Jul 01 00:17:12 volumio9 volumio-remote-updater[609]: No alpha test mode Jul 01 00:17:12 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 01 00:17:13 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 01 00:17:13 volumio9 volumio[820]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 01 00:17:13 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 01 00:17:13 volumio9 sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 01 00:17:13 volumio9 sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:13 volumio9 sudo[1654]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:13 volumio9 sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 01 00:17:13 volumio9 sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 00:17:13 volumio9 sudo[1656]: pam_unix(sudo:session): session closed for user root Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:14 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:14 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:14 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetBrowseSources Jul 01 00:17:14 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:17:15 volumio9 volumio[1362]: .................................................................................................................................++++ Jul 01 00:17:15 volumio9 volumio[1362]: e is 65537 (0x010001) Jul 01 00:17:15 volumio9 volumio[1362]: writing RSA key Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:15 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:15 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:15 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:15 volumio9 volumio[820]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 01 00:17:15 volumio9 volumio[820]: info: Completed starting MyVolumio Plugin Jul 01 00:17:15 volumio9 volumio[820]: [Metrics] CommandRouter: 39s 731.91ms Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::volumiosetStartupVolume Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::Close All Modals sent Jul 01 00:17:15 volumio9 volumio[820]: info: CoreCommandRouter::Close All Modals sent Jul 01 00:17:16 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 01 00:17:16 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 00:17:16 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 01 00:17:18 volumio9 go-librespot[1155]: time="2025-07-01T00:17:18+07:00" level=trace msg="sent dealer ping" Jul 01 00:17:18 volumio9 go-librespot[1155]: time="2025-07-01T00:17:18+07:00" level=trace msg="received dealer pong" Jul 01 00:17:19 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 01 00:17:21 volumio9 kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Jul 01 00:17:21 volumio9 kernel: xhci_hcd 0000:01:00.0: @00000004295161c0 00000000 00000000 0e000000 02028000 Jul 01 00:17:22 volumio9 volumio[820]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 01 00:17:22 volumio9 volumio[820]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetVisibleSources Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetQueue Jul 01 00:17:22 volumio9 volumio[820]: info: CoreStateMachine::getQueue Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getQueue Jul 01 00:17:22 volumio9 volumio[820]: info: Listing playlists Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 01 00:17:22 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:22 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:22 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:22 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetVisibleSources Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetQueue Jul 01 00:17:22 volumio9 volumio[820]: info: CoreStateMachine::getQueue Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getQueue Jul 01 00:17:22 volumio9 volumio[820]: info: Listing playlists Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 01 00:17:22 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:22 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:22 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 01 00:17:22 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 01 00:17:22 volumio9 volumio[820]: info: BOOT COMPLETED Jul 01 00:17:23 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 01 00:17:28 volumio9 volumio[820]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 01 00:17:28 volumio9 volumio[820]: info: Received Get System Version Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:17:28 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:28 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:28 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:28 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:29 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:29 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:29 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:29 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:29 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:29 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 00:17:29 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:29 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:29 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:29 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:30 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 01 00:17:32 volumio9 volumio[820]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 01 00:17:32 volumio9 volumio[820]: info: Received Get System Version Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 01 00:17:32 volumio9 volumio[820]: info: Received Get System Info Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:32 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:32 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:32 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:34 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 00:17:34 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 00:17:34 volumio9 volumio[820]: info: Discovery: Getting this device information Jul 01 00:17:34 volumio9 volumio[820]: info: CoreCommandRouter::volumioGetState Jul 01 00:17:34 volumio9 volumio[820]: info: CorePlayQueue::getTrack 0 Jul 01 00:17:34 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 00:17:34 volumio9 volumio[820]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 00:17:34 volumio9 volumio[820]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 00:17:34 volumio9 volumio[820]: TypeError: Cannot read property 'length' of undefined Jul 01 00:17:34 volumio9 volumio[820]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 01 00:17:34 volumio9 volumio[820]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 01 00:17:34 volumio9 volumio[820]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 01 00:17:34 volumio9 volumio[820]: at Parser.emit (events.js:315:20) Jul 01 00:17:34 volumio9 volumio[820]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 01 00:17:34 volumio9 volumio[820]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 01 00:17:34 volumio9 volumio[820]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 01 00:17:34 volumio9 volumio[820]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 01 00:17:34 volumio9 volumio[820]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 01 00:17:34 volumio9 volumio[820]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 01 00:17:34 volumio9 volumio[820]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 01 00:17:34 volumio9 volumio[820]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 01 00:17:34 volumio9 volumio[820]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 01 00:17:34 volumio9 volumio[820]: at IncomingMessage.emit (events.js:327:22) Jul 01 00:17:34 volumio9 volumio[820]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 01 00:17:34 volumio9 volumio[820]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 01 00:17:34 volumio9 volumio[820]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 00:17:35 volumio9 sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 00:16 Jul 01 00:17:35 volumio9 sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"