-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-09-16 08:05:54 CEST. -- Sep 16 08:05:02 volumio systemd-timedated[902]: Changed local time to Tue Sep 16 08:05:02 2025 Sep 16 08:05:02 volumio sudo[900]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:02 volumio volumio-time-update[557]: volumio-time-update-util: System time updated successfully. Sep 16 08:05:02 volumio systemd[1]: Starting Daily apt download activities... Sep 16 08:05:02 volumio systemd[1]: Started Volumio Time Update Utility. Sep 16 08:05:02 volumio ntpd[864]: Soliciting pool server 217.144.138.234 Sep 16 08:05:02 volumio ntpd[864]: Soliciting pool server 188.245.32.133 Sep 16 08:05:03 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:03 volumio volumio[811]: info: ----- Volumio3 ---- Sep 16 08:05:03 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:03 volumio volumio[811]: info: ----- System startup ---- Sep 16 08:05:03 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:03 volumio nmbd[724]: [2025/09/16 08:05:03.199271, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 16 08:05:03 volumio systemd[1]: Started Samba NMB Daemon. Sep 16 08:05:03 volumio nmbd[724]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 16 08:05:03 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 16 08:05:03 volumio ntpd[864]: Soliciting pool server 144.76.43.40 Sep 16 08:05:03 volumio ntpd[864]: Soliciting pool server 144.91.126.59 Sep 16 08:05:03 volumio ntpd[864]: Soliciting pool server 188.40.128.242 Sep 16 08:05:03 volumio winbindd[932]: [2025/09/16 08:05:03.761891, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 16 08:05:03 volumio winbindd[932]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 16 08:05:03 volumio winbindd[932]: [2025/09/16 08:05:03.883004, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 16 08:05:03 volumio systemd[1]: Started Samba Winbind Daemon. Sep 16 08:05:03 volumio winbindd[932]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 16 08:05:03 volumio systemd[1]: Starting Samba SMB Daemon... Sep 16 08:05:04 volumio ntpd[864]: Soliciting pool server 159.69.64.189 Sep 16 08:05:04 volumio ntpd[864]: Soliciting pool server 141.144.246.224 Sep 16 08:05:04 volumio ntpd[864]: Soliciting pool server 139.162.187.236 Sep 16 08:05:04 volumio ntpd[864]: Soliciting pool server 188.68.34.173 Sep 16 08:05:04 volumio smbd[937]: [2025/09/16 08:05:04.715039, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 16 08:05:04 volumio systemd[1]: Started Samba SMB Daemon. Sep 16 08:05:04 volumio smbd[937]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 16 08:05:04 volumio systemd[1]: Reached target Multi-User System. Sep 16 08:05:04 volumio systemd[1]: Reached target Graphical Interface. Sep 16 08:05:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 16 08:05:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 16 08:05:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 16 08:05:05 volumio volumio[811]: info: MYVOLUMIO Environment detected Sep 16 08:05:05 volumio volumio[811]: info: Plugin folders cleanup Sep 16 08:05:05 volumio volumio[811]: info: Scanning into folder /volumio/app/plugins/ Sep 16 08:05:05 volumio volumio[811]: info: Scanning category audio_interface Sep 16 08:05:05 volumio volumio[811]: info: Scanning category miscellanea Sep 16 08:05:05 volumio volumio[811]: info: Scanning category music_service Sep 16 08:05:05 volumio volumio[811]: info: Scanning category plugins.json Sep 16 08:05:05 volumio volumio[811]: info: Scanning category system_controller Sep 16 08:05:05 volumio volumio[811]: info: Scanning category user_interface Sep 16 08:05:05 volumio volumio[811]: info: Scanning into folder /data/plugins/ Sep 16 08:05:05 volumio volumio[811]: info: Scanning category music_service Sep 16 08:05:05 volumio volumio[811]: info: Scanning category system_controller Sep 16 08:05:05 volumio volumio[811]: info: Scanning category user_interface Sep 16 08:05:05 volumio volumio[811]: info: Plugin folders cleanup completed Sep 16 08:05:05 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:05 volumio volumio[811]: info: ----- Core plugins startup ---- Sep 16 08:05:05 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:05 volumio volumio[811]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 08:05:05 volumio volumio[811]: info: Adding plugin upnp to MyMusic Plugins Sep 16 08:05:05 volumio volumio[811]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 08:05:05 volumio volumio[811]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 08:05:05 volumio volumio[811]: info: Loading plugins from folder /data/plugins/ Sep 16 08:05:05 volumio volumio[811]: info: Loading plugin "system"... Sep 16 08:05:05 volumio volumio[811]: info: Loading plugin "appearance"... Sep 16 08:05:05 volumio ntpd[864]: Soliciting pool server 185.248.189.10 Sep 16 08:05:05 volumio ntpd[864]: Soliciting pool server 90.187.112.137 Sep 16 08:05:05 volumio ntpd[864]: Soliciting pool server 49.12.199.148 Sep 16 08:05:05 volumio ntpd[864]: Soliciting pool server 152.70.19.169 Sep 16 08:05:05 volumio systemd[1]: apt-daily.service: Succeeded. Sep 16 08:05:05 volumio systemd[1]: Started Daily apt download activities. Sep 16 08:05:05 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 16 08:05:06 volumio ntpd[864]: Soliciting pool server 85.214.38.116 Sep 16 08:05:06 volumio ntpd[864]: Soliciting pool server 5.189.151.39 Sep 16 08:05:06 volumio ntpd[864]: Soliciting pool server 144.76.76.107 Sep 16 08:05:06 volumio kernel: Bluetooth: hci0: BCM: failed to write update baudrate (-110) Sep 16 08:05:06 volumio kernel: Bluetooth: hci0: Failed to set baudrate Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "network"... Sep 16 08:05:07 volumio volumio[811]: info: Refreshing Cached IP Addresses Sep 16 08:05:07 volumio sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 08:05:07 volumio sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:07 volumio sudo[1000]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:07 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 08:05:07 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "services"... Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "alsa_controller"... Sep 16 08:05:07 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:07 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "wizard"... Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "networkfs"... Sep 16 08:05:07 volumio volumio[811]: info: Starting Udev Watcher for removable devices Sep 16 08:05:07 volumio volumio[811]: info: Ignoring mount for partition: boot Sep 16 08:05:07 volumio volumio[811]: info: Ignoring mount for partition: volumio Sep 16 08:05:07 volumio volumio[811]: info: Ignoring mount for partition: volumio_data Sep 16 08:05:07 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "volumio_command_line_client"... Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "upnp"... Sep 16 08:05:07 volumio volumio[811]: info: [1758002707419] Starting Upmpd Daemon Sep 16 08:05:07 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "my_music"... Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "mpd"... Sep 16 08:05:07 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 16 08:05:07 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 16 08:05:07 volumio volumio[811]: info: Loading plugin "upnp_browser"... Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "alarm-clock"... Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "airplay_emulation"... Sep 16 08:05:08 volumio volumio[811]: info: Starting Shairport Sync Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "last_100"... Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "webradio"... Sep 16 08:05:08 volumio kernel: Bluetooth: hci0: command tx timeout Sep 16 08:05:08 volumio volumio-remote-updater[564]: [2025-09-16 08:05:08] [connect] Successful connection Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "i2s_dacs"... Sep 16 08:05:08 volumio volumio[811]: info: I2S DAC not set, start Auto-detection Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "volumiodiscovery"... Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** For more information see Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 08:05:08 volumio node[811]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 08:05:08 volumio volumio[811]: *** WARNING *** For more information see Sep 16 08:05:08 volumio node[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 08:05:08 volumio node[811]: *** WARNING *** For more information see Sep 16 08:05:08 volumio node[811]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 08:05:08 volumio node[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 08:05:08 volumio node[811]: *** WARNING *** For more information see Sep 16 08:05:08 volumio volumio[811]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 08:05:08 volumio volumio[811]: info: Discovery: Started advertising with name: Volumio Sep 16 08:05:08 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 08:05:08 volumio volumio[811]: info: Loading plugin "ytcr"... Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a54f589a does not match aorg 0000000000.00000000 from server@152.70.19.169 xmt 0xec737c97.0cb744b4 Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a56617db does not match aorg 0000000000.00000000 from server@139.162.187.236 xmt 0xec737c97.0b459b03 Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a563229d does not match aorg 0000000000.00000000 from server@185.248.189.10 xmt 0xec737c97.0ce9adad Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a55f5deb does not match aorg 0000000000.00000000 from server@49.12.199.148 xmt 0xec737c97.0d1af13f Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a564bd3e does not match aorg 0000000000.00000000 from server@159.69.64.189 xmt 0xec737c97.0d2331a2 Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a56948b8 does not match aorg 0000000000.00000000 from server@144.76.43.40 xmt 0xec737c97.0d95efff Sep 16 08:05:11 volumio ntpd[864]: receive: Unexpected origin timestamp 0xec737c96.a55aca32 does not match aorg 0000000000.00000000 from server@90.187.112.137 xmt 0xec737c97.0d89aa68 Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "autostart"... Sep 16 08:05:15 volumio volumio[811]: info: Applying required configuration parameters for plugin autostart Sep 16 08:05:15 volumio volumio[811]: info: AutoStart - onVolumioStart - read config.json Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "outputs"... Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "albumart"... Sep 16 08:05:15 volumio volumio[811]: info: Plugin example_plugin is not enabled Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "inputs"... Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "updater_comm"... Sep 16 08:05:15 volumio volumio[811]: info: Plugin mpdemulation is not enabled Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "rest_api"... Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "websocket"... Sep 16 08:05:15 volumio volumio[811]: info: Starting Socket.io Server version 2.3.0 Sep 16 08:05:15 volumio volumio[811]: info: Loading plugin "80s80s"... Sep 16 08:05:16 volumio volumio[811]: Forking 3 albumart workers Sep 16 08:05:16 volumio volumio[811]: info: Applying required configuration parameters for plugin 80s80s Sep 16 08:05:16 volumio volumio[811]: info: [1758002716519] [80s80s] API delay: 30 Sep 16 08:05:16 volumio volumio[811]: info: Loading plugin "backup_restore"... Sep 16 08:05:16 volumio volumio[811]: info: Applying required configuration parameters for plugin backup_restore Sep 16 08:05:16 volumio volumio[811]: info: Loading plugin "Systeminfo"... Sep 16 08:05:17 volumio volumio[811]: Starting albumart workers Sep 16 08:05:17 volumio volumio[811]: Starting albumart workers Sep 16 08:05:17 volumio volumio[811]: Starting albumart workers Sep 16 08:05:17 volumio bthelper[681]: Can't init device hci0: Connection timed out (110) Sep 16 08:05:17 volumio systemd[1]: bthelper@hci0.service: Main process exited, code=exited, status=1/FAILURE Sep 16 08:05:17 volumio systemd[1]: bthelper@hci0.service: Failed with result 'exit-code'. Sep 16 08:05:17 volumio kernel: Bluetooth: hci0: BCM: Reset failed (-110) Sep 16 08:05:17 volumio systemd[1]: Failed to start Raspberry Pi bluetooth helper. Sep 16 08:05:17 volumio systemd[1]: Starting Bluetooth service... Sep 16 08:05:17 volumio bluetoothd[1083]: Bluetooth daemon 5.50 Sep 16 08:05:17 volumio bluetoothd[1083]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 16 08:05:17 volumio systemd[1]: Started Bluetooth service. Sep 16 08:05:17 volumio systemd[1]: Reached target Bluetooth. Sep 16 08:05:17 volumio bluetoothd[1083]: Starting SDP server Sep 16 08:05:17 volumio systemd[1]: Startup finished in 13.617s (kernel) + 26.715s (userspace) = 40.332s. Sep 16 08:05:17 volumio bluetoothd[1083]: Excluding (cli) sap Sep 16 08:05:17 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 16 08:05:17 volumio kernel: Bluetooth: BNEP filters: protocol multicast Sep 16 08:05:17 volumio kernel: Bluetooth: BNEP socket layer initialized Sep 16 08:05:17 volumio bluetoothd[1083]: Bluetooth management interface 1.22 initialized Sep 16 08:05:17 volumio dbus-daemon[578]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1083 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 16 08:05:17 volumio systemd[1]: Starting Hostname Service... Sep 16 08:05:17 volumio volumio[811]: info: Loading i18n strings for locale de Sep 16 08:05:17 volumio volumio[811]: Updating browse sources language Sep 16 08:05:17 volumio dbus-daemon[578]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 16 08:05:17 volumio systemd[1]: Started Hostname Service. Sep 16 08:05:17 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::initPlayerControls Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 08:05:18 volumio volumio[811]: Express server listening on port 3000 Sep 16 08:05:18 volumio volumio[811]: [Metrics] WebUI: 16s 7.17ms Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::resetVolumioState Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::getcurrentVolume Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:18 volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 08:05:18 volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:18 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 08:05:18 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:18 volumio volumio[811]: info: Volumio Network Manager: Network status updated: 1 Sep 16 08:05:18 volumio volumio[811]: info: VolumeController:: Volume=65 Mute =false Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::updateTrackBlock Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrackBlock Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 08:05:18 volumio volumio[811]: info: Reloading queue from file Sep 16 08:05:18 volumio volumio[811]: info: Setting Device type: Raspberry PI Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::setRepeat null single undefined Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::setRandom true Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:18 volumio volumio[811]: info: Discovery: adding 6fe36668-8ecb-46a5-853f-35cff9042c58 Sep 16 08:05:18 volumio volumio[811]: info: Discovery: Found device Volumio Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:18 volumio volumio[811]: info: Completed loading Core Plugins Sep 16 08:05:18 volumio volumio[811]: info: Preparing to generate the ALSA configuration file Sep 16 08:05:18 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 16 08:05:18 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio volumio[811]: info: VolumeController:: Volume=65 Mute =false Sep 16 08:05:18 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:18 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:18 volumio volumio[811]: info: Asound.conf file unchanged, so no further update is needed Sep 16 08:05:18 volumio volumio[811]: info: Output device has changed, restarting MPD Sep 16 08:05:18 volumio volumio[811]: info: Output device has changed, restarting Shairport Sync Sep 16 08:05:18 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 08:05:18 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 08:05:18 volumio volumio[811]: info: ___________ START PLUGINS ___________ Sep 16 08:05:18 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:18 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 16 08:05:18 volumio volumio[811]: info: ControllerMpd::onStart: Initializing MPD Sep 16 08:05:18 volumio volumio[811]: info: Creating MPD Configuration file Sep 16 08:05:18 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 08:05:18 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 08:05:18 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 08:05:18 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 08:05:18 volumio volumio[811]: info: [1758002718807] CoreMusicLibrary::Adding element Medienserver Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 08:05:18 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:18 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:18 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:18 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:18 volumio systemd[1]: mpd.service: Succeeded. Sep 16 08:05:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 08:05:18 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 08:05:18 volumio volumio[811]: info: [1758002718974] CoreMusicLibrary::Adding element Last_100 Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 08:05:18 volumio volumio[811]: info: [1758002718977] CoreMusicLibrary::Adding element Webradio Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 08:05:18 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 08:05:18 volumio volumio[811]: info: Initializing BBC Radios Sep 16 08:05:19 volumio systemd[1]: Starting Music Player Daemon... Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: AutoStart - onStart Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 08:05:19 volumio volumio[811]: info: [1758002719117] CoreMusicLibrary::Adding element 80s80s Radio Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 08:05:19 volumio volumio[811]: Cannot find translation for source 80s80s Radio Sep 16 08:05:19 volumio volumio[811]: info: Volumio Calling Home Sep 16 08:05:19 volumio sudo[1168]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 08:05:19 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:19 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:19 volumio volumio[811]: info: MPD Permissions set Sep 16 08:05:19 volumio volumio[811]: info: MPD Permissions set Sep 16 08:05:19 volumio volumio[811]: info: Upmpdcli Daemon Started Sep 16 08:05:19 volumio volumio[811]: info: Volumio called home Sep 16 08:05:19 volumio volumio[811]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:19 volumio volumio[811]: info: Starting Shairport Sync Sep 16 08:05:19 volumio volumio[811]: info: Starting Shairport Sync Sep 16 08:05:19 volumio volumio[811]: info: Starting Shairport Sync Sep 16 08:05:19 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 08:05:19 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:19 volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 08:05:19 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:19 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 08:05:19 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:20 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Sep 16 08:05:20 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 08:05:20 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 08:05:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 08:05:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 08:05:20 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:20 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:20 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:20 volumio volumio[811]: info: Shairport-Sync Started Sep 16 08:05:20 volumio volumio[811]: Error adding Membership: Error: addMembership EINVAL Sep 16 08:05:20 volumio volumio[811]: info: Shairport-Sync Started Sep 16 08:05:20 volumio volumio[811]: info: Shairport-Sync Started Sep 16 08:05:20 volumio volumio[811]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 16 08:05:20 volumio volumio[811]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 08:05:20 volumio mpd[1182]: Sep 16 08:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 08:05:20 volumio systemd[1]: Started Music Player Daemon. Sep 16 08:05:20 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:20 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:20 volumio volumio[811]: error: MPD error: The expression evaluated to a falsy value: Sep 16 08:05:20 volumio volumio[811]: assert.ok(self.idling) Sep 16 08:05:20 volumio volumio[811]: error: The expression evaluated to a falsy value: Sep 16 08:05:20 volumio volumio[811]: assert.ok(self.idling) Sep 16 08:05:21 volumio volumio[811]: info: MPD running with PID1182 Sep 16 08:05:21 volumio volumio[811]: ,establishing connection Sep 16 08:05:21 volumio volumio[811]: error: MPD error: The expression evaluated to a falsy value: Sep 16 08:05:21 volumio volumio[811]: assert.ok(self.idling) Sep 16 08:05:21 volumio volumio[811]: error: The expression evaluated to a falsy value: Sep 16 08:05:21 volumio volumio[811]: assert.ok(self.idling) Sep 16 08:05:21 volumio volumio[1146]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 16 08:05:21 volumio volumio[811]: info: VolumeController:: Volume=65 Mute =false Sep 16 08:05:21 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Sep 16 08:05:21 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:21 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:21 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:21 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:21 volumio volumio[811]: error: updateQueue error: null Sep 16 08:05:21 volumio volumio[811]: info: Completed starting Core Plugins Sep 16 08:05:21 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:21 volumio volumio[811]: info: ----- MyVolumio plugins startup ---- Sep 16 08:05:21 volumio volumio[811]: info: ------------------------------------------- Sep 16 08:05:21 volumio volumio[811]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 16 08:05:24 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 16 08:05:24 volumio volumio-remote-updater[564]: [2025-09-16 08:05:24] [connect] Successful connection Sep 16 08:05:24 volumio volumio-remote-updater[564]: [2025-09-16 08:05:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758002724 101 Sep 16 08:05:24 volumio volumio[811]: 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: 2 Sep 16 08:05:25 volumio volumio[811]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin bluetooth to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin multiroom to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin metavolumio to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin cd_controller to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 16 08:05:29 volumio volumio[811]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 16 08:05:31 volumio volumio[811]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 16 08:05:31 volumio volumio[811]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 16 08:05:31 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:31 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:31 volumio volumio[811]: info: Starting MyVolumio Remote Streaming Endpoints Sep 16 08:05:31 volumio volumio[811]: info: MyVolumio login type: Token Sep 16 08:05:31 volumio volumio[811]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 16 08:05:31 volumio volumio[811]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 16 08:05:32 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 16 08:05:32 volumio volumio[811]: info: Starting Streaming Service Transparent Proxy Sep 16 08:05:32 volumio volumio[811]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 16 08:05:32 volumio volumio[811]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 16 08:05:32 volumio volumio[811]: info: Streaming services startup Sep 16 08:05:32 volumio volumio[811]: info: Starting Streaming Daemon Sep 16 08:05:32 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 08:05:32 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:32 volumio volumio[811]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 16 08:05:32 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:33 volumio volumio[811]: error: Cannot start Volumio Streaming Daemon Sep 16 08:05:33 volumio volumio[811]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 08:05:33 volumio volumio[811]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 08:05:33 volumio volumio[811]: error: MyVolumio Custom Token format not valid, refreshing it Sep 16 08:05:33 volumio volumio[811]: info: MyVolumio login type: Token Sep 16 08:05:34 volumio volumio[811]: STREAMING PROXY: Starting server on port 3245 Sep 16 08:05:34 volumio volumio[811]: Node JS runtime: 14 Sep 16 08:05:34 volumio volumio[811]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 16 08:05:35 volumio volumio[811]: info: MyVolumio token set successfully Sep 16 08:05:35 volumio volumio[811]: info: MYVOLUMIO: Adding device Sep 16 08:05:35 volumio volumio[811]: info: MYVOLUMIO: Evaluating Server Sep 16 08:05:35 volumio volumio[811]: info: MyVolumio status changed Sep 16 08:05:35 volumio volumio[811]: info: Streaming services startup Sep 16 08:05:35 volumio volumio[811]: info: Starting Streaming Daemon Sep 16 08:05:35 volumio volumio[811]: info: Removing browser output: myVolumio user plan is not superstar Sep 16 08:05:35 volumio volumio[811]: info: Removing audio output: Sep 16 08:05:35 volumio volumio[811]: info: Stoppping Tunnel 1 Sep 16 08:05:35 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 08:05:35 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:35 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 16 08:05:35 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:35 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 08:05:35 volumio volumio[811]: error: Cannot start Volumio Streaming Daemon Sep 16 08:05:35 volumio volumio[811]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 08:05:35 volumio volumio[811]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 08:05:35 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Sep 16 08:05:35 volumio volumio[811]: info: Remote SSH Stopped Sep 16 08:05:35 volumio volumio[811]: info: Setting Geolocation for MyVolumio to eu4 Sep 16 08:05:35 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:36 volumio volumio[811]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 16 08:05:36 volumio volumio[811]: info: Updating MyVolumio device info Sep 16 08:05:36 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:37 volumio volumio[811]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 16 08:05:39 volumio volumio[811]: info: AutoStart - Plugin is starting Sep 16 08:05:39 volumio volumio[811]: info: CoreCommandRouter::volumioGetQueue Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::getQueue Sep 16 08:05:39 volumio volumio[811]: info: CorePlayQueue::getQueue Sep 16 08:05:39 volumio volumio[811]: info: AutoStart - start playing Sep 16 08:05:39 volumio volumio[811]: info: AutoStart - start playing with no specific position Sep 16 08:05:39 volumio volumio[811]: info: CoreCommandRouter::volumioPlay Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::play index 0 Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::stop Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::play index undefined Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 08:05:39 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:39 volumio volumio[811]: info: CoreStateMachine::startPlaybackTimer Sep 16 08:05:39 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:39 volumio volumio[811]: info: [1758002739123] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Sep 16 08:05:39 volumio volumio[811]: info: [1758002739125] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Sep 16 08:05:40 volumio volumio[1146]: ............................................................................................................................................++++ Sep 16 08:05:40 volumio volumio[811]: info: MYVOLUMIO: Adding device Sep 16 08:05:40 volumio volumio[811]: info: MYVOLUMIO: Evaluating Server Sep 16 08:05:40 volumio volumio[811]: info: Setting Geolocation for MyVolumio to eu4 Sep 16 08:05:40 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:41 volumio volumio[811]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 16 08:05:41 volumio volumio[811]: info: Updating MyVolumio device info Sep 16 08:05:41 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:42 volumio volumio[811]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 16 08:05:43 volumio volumio[1146]: ..........................++++ Sep 16 08:05:43 volumio volumio[1146]: e is 65537 (0x010001) Sep 16 08:05:43 volumio volumio[1146]: writing RSA key Sep 16 08:05:46 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 08:05:46 volumio volumio[811]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 16 08:05:46 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Sep 16 08:05:46 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:47 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 16 08:05:49 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 08:05:49 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:49 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 08:05:49 volumio volumio-remote-updater[564]: No test mode Sep 16 08:05:49 volumio volumio-remote-updater[564]: No alpha test mode Sep 16 08:05:49 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 16 08:05:49 volumio volumio[811]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix plugin installations due to apt repository
    • \n
  • Fix chromecast playback
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.832","updateavailable":true} Sep 16 08:05:49 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 08:05:51 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 08:05:52 volumio volumio[811]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 16 08:05:52 volumio volumio[811]: info: Completed starting MyVolumio Plugin Sep 16 08:05:52 volumio volumio[811]: [Metrics] CommandRouter: 49s 487.50ms Sep 16 08:05:52 volumio volumio[811]: info: CoreCommandRouter::volumiosetStartupVolume Sep 16 08:05:52 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 08:05:52 volumio volumio[811]: info: VolumeController:: Setting startup Volume 65 Sep 16 08:05:52 volumio volumio[811]: info: VolumeController::SetAlsaVolume65 Sep 16 08:05:52 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 08:05:52 volumio volumio[811]: info: CoreCommandRouter::Close All Modals sent Sep 16 08:05:52 volumio volumio[811]: info: CoreCommandRouter::Close All Modals sent Sep 16 08:05:53 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Sep 16 08:05:53 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:53 volumio volumio[811]: info: CoreStateMachine::pushState Sep 16 08:05:53 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Sep 16 08:05:53 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 08:05:53 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Sep 16 08:05:53 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 16 08:05:53 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 08:05:54 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 16 08:05:54 volumio volumio[811]: info: [1758002754314] [80s80s] Failed to query api, status code: 504 Sep 16 08:05:54 volumio volumio[811]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 08:05:54 volumio volumio[811]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Sep 16 08:05:54 volumio volumio[811]: at Object.openSync (fs.js:476:3) Sep 16 08:05:54 volumio volumio[811]: at Object.readFileSync (fs.js:377:35) Sep 16 08:05:54 volumio volumio[811]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Sep 16 08:05:54 volumio volumio[811]: at Object.onceWrapper (events.js:422:26) Sep 16 08:05:54 volumio volumio[811]: at ClientRequest.emit (events.js:315:20) Sep 16 08:05:54 volumio volumio[811]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Sep 16 08:05:54 volumio volumio[811]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Sep 16 08:05:54 volumio volumio[811]: at Socket.socketOnData (_http_client.js:509:22) Sep 16 08:05:54 volumio volumio[811]: at Socket.emit (events.js:315:20) Sep 16 08:05:54 volumio volumio[811]: at addChunk (internal/streams/readable.js:309:12) Sep 16 08:05:54 volumio volumio[811]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 16 08:05:54 volumio volumio[811]: at Socket.Readable.push (internal/streams/readable.js:223:10) Sep 16 08:05:54 volumio volumio[811]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Sep 16 08:05:54 volumio volumio[811]: errno: -2, Sep 16 08:05:54 volumio volumio[811]: syscall: 'open', Sep 16 08:05:54 volumio volumio[811]: code: 'ENOENT', Sep 16 08:05:54 volumio volumio[811]: path: '/data/plugins/music_service/80s80s/fake-data.json' Sep 16 08:05:54 volumio volumio[811]: } Sep 16 08:05:54 volumio volumio[811]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 08:05:54 volumio sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 08:04 Sep 16 08:05:54 volumio sudo[1376]: 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="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"