Aug 30 21:37:07 volumiostreamer ntpd[818]: CLOCK: time stepped by 771620.230417
Aug 30 21:37:07 volumiostreamer ntpd[818]: CLOCK: time changed from 2025-08-21 to 2025-08-30
Aug 30 21:37:07 volumiostreamer ntpd[818]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Aug 30 21:37:07 volumiostreamer sudo[1143]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: Pool taking: 212.227.232.46
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: Pool taking: 5.250.191.170
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: Pool taking: 84.77.195.114
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: Pool taking: 5.250.184.159
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Aug 30 21:37:10 volumiostreamer ntpd[818]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "outputs"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "albumart"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Plugin example_plugin is not enabled
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "inputs"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "updater_comm"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Plugin mpdemulation is not enabled
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "rest_api"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "websocket"...
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Starting Socket.io Server version 1.7.4
Aug 30 21:37:10 volumiostreamer volumio[1054]: info: Loading plugin "fusiondsp"...
Aug 30 21:37:10 volumiostreamer volumio[1175]: Forking 3 albumart workers
Aug 30 21:37:11 volumiostreamer volumio[1054]: info: Applying required configuration parameters for plugin fusiondsp
Aug 30 21:37:12 volumiostreamer volumio[1187]: Starting albumart workers
Aug 30 21:37:13 volumiostreamer volumio[1054]: info: Loading i18n strings for locale es
Aug 30 21:37:13 volumiostreamer volumio[1054]: Updating browse sources language
Aug 30 21:37:13 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: Pool taking: 90.68.206.60
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: Pool taking: 178.255.228.77
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: Pool taking: 162.159.200.1
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: Pool taking: 92.113.12.78
Aug 30 21:37:14 volumiostreamer ntpd[818]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::initPlayerControls
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 30 21:37:14 volumiostreamer volumio[1054]: Express server listening on port 3000
Aug 30 21:37:14 volumiostreamer volumio[1054]: [Metrics] WebUI: 14s 802.03ms
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreStateMachine::resetVolumioState
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreStateMachine::getcurrentVolume
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume
Aug 30 21:37:14 volumiostreamer sudo[1242]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 30 21:37:14 volumiostreamer sudo[1242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:14 volumiostreamer volumio[1186]: Starting albumart workers
Aug 30 21:37:14 volumiostreamer sudo[1244]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 30 21:37:14 volumiostreamer sudo[1244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:14 volumiostreamer sudo[1242]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:14 volumiostreamer sudo[1244]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: Volumio Network Manager: Network status updated: 2
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: VolumeController:: Volume=40 Mute =false
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreStateMachine::pushState
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioPushState
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreStateMachine::updateTrackBlock
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrackBlock
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume
Aug 30 21:37:14 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Executing start script for DAC IQaudIO DAC Plus
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Reloading queue from file
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreStateMachine::setRepeat false single undefined
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreStateMachine::pushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioPushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreStateMachine::setRandom null
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreStateMachine::pushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioPushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Setting Device type: Raspberry PI
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: DAC script executed
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Completed loading Core Plugins
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Preparing to generate the ALSA configuration file
Aug 30 21:37:15 volumiostreamer volumio[1185]: Starting albumart workers
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Reading ALSA contributions from plugins.
Aug 30 21:37:15 volumiostreamer sudo[1267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 30 21:37:15 volumiostreamer sudo[1267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: VolumeController:: Volume=40 Mute =false
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreStateMachine::pushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioPushState
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Discovery: A device disappeared from network
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase')
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Discovery: A device disappeared from network
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase')
Aug 30 21:37:15 volumiostreamer systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Aug 30 21:37:15 volumiostreamer systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Aug 30 21:37:15 volumiostreamer systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Aug 30 21:37:15 volumiostreamer systemd[1]: e2scrub_all.service: Deactivated successfully.
Aug 30 21:37:15 volumiostreamer systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Aug 30 21:37:15 volumiostreamer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 30 21:37:15 volumiostreamer systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Asound.conf file unchanged, so no further update is needed
Aug 30 21:37:15 volumiostreamer sudo[1267]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Output device has changed, restarting MPD
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Output device has changed, restarting Shairport Sync
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:15 volumiostreamer systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Aug 30 21:37:15 volumiostreamer systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Aug 30 21:37:15 volumiostreamer sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 30 21:37:15 volumiostreamer sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:15 volumiostreamer sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 30 21:37:15 volumiostreamer sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:15 volumiostreamer sudo[1281]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: ___________ START PLUGINS ___________
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: ControllerMpd::onStart: Initializing MPD
Aug 30 21:37:15 volumiostreamer volumio[1054]: info: Creating MPD Configuration file
Aug 30 21:37:15 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 30 21:37:15 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 30 21:37:15 volumiostreamer sudo[1305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 30 21:37:15 volumiostreamer sudo[1305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:15 volumiostreamer sudo[1305]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 30 21:37:16 volumiostreamer sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: [1756582636032] CoreMusicLibrary::Adding element Media Servers
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 21:37:16 volumiostreamer sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:16 volumiostreamer sudo[1306]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 30 21:37:16 volumiostreamer sudo[1306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 30 21:37:16 volumiostreamer sudo[1311]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Aug 30 21:37:16 volumiostreamer sudo[1306]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:16 volumiostreamer systemd[1]: mpd.service: Deactivated successfully.
Aug 30 21:37:16 volumiostreamer systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 30 21:37:16 volumiostreamer systemd[1]: mpd.socket: Deactivated successfully.
Aug 30 21:37:16 volumiostreamer systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 30 21:37:16 volumiostreamer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:16 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 30 21:37:16 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:16 volumiostreamer sudo[1315]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 30 21:37:16 volumiostreamer sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 30 21:37:16 volumiostreamer sudo[1323]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Aug 30 21:37:16 volumiostreamer sudo[1315]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: [1756582636460] CoreMusicLibrary::Adding element Last_100
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: [1756582636508] CoreMusicLibrary::Adding element Webradio
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: Initializing BBC Radios
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: [1756582636886] CoreMusicLibrary::Adding element YouTube Music
Aug 30 21:37:16 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 21:37:16 volumiostreamer volumio[1054]: Cannot find translation for source YouTube Music
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: Loading i18n strings for locale es
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: FusionDsp - mixtype--------------------- Hardware
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: Volumio Calling Home
Aug 30 21:37:17 volumiostreamer volumio[1054]: info: Preparing to generate the ALSA configuration file
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: Reading ALSA contributions from plugins.
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: MPD Permissions set
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: MPD Permissions set
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: Upmpdcli Daemon Started
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: camilladsp service started and running in background, instance 1
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: FusionDsp loaded
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 30 21:37:19 volumiostreamer volumio[1054]: error: FusionDsp - Socket connection not established
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: FusionDsp - Reporting Fusion DSP Enabled
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: Adding Signal Path Element [object Object]
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: Adding fusiondspeq DSP Signal Path Element
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: FusionDsp - ---- installed callbackRead
Aug 30 21:37:19 volumiostreamer volumio[1054]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 30 21:37:19 volumiostreamer sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Aug 30 21:37:19 volumiostreamer sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: Volumio called home
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Aug 30 21:37:20 volumiostreamer sudo[1367]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:20 volumiostreamer volumio[1054]: info: Starting Shairport Sync
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Starting Shairport Sync
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Starting Shairport Sync
Aug 30 21:37:21 volumiostreamer sudo[1387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 30 21:37:21 volumiostreamer sudo[1387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:21 volumiostreamer sudo[1386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 30 21:37:21 volumiostreamer sudo[1386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Asound.conf file unchanged, so no further update is needed
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Output device has changed, restarting MPD
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 30 21:37:21 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer systemd[1]: shairport-sync.service: Consumed 1.556s CPU time.
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Output device has changed, restarting Shairport Sync
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:21 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer sudo[1393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 30 21:37:21 volumiostreamer sudo[1387]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:21 volumiostreamer sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 30 21:37:21 volumiostreamer sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:21 volumiostreamer sudo[1393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:21 volumiostreamer sudo[1393]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 30 21:37:21 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer sudo[1396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 30 21:37:21 volumiostreamer sudo[1396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:21 volumiostreamer sudo[1386]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 30 21:37:21 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:21 volumiostreamer sudo[1389]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 30 21:37:21 volumiostreamer systemd[1]: mpd.service: Deactivated successfully.
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 30 21:37:21 volumiostreamer systemd[1]: mpd.service: Consumed 3.696s CPU time.
Aug 30 21:37:21 volumiostreamer systemd[1]: mpd.socket: Deactivated successfully.
Aug 30 21:37:21 volumiostreamer systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 30 21:37:21 volumiostreamer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 30 21:37:21 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 30 21:37:21 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: MPD Permissions set
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Shairport-Sync Started
Aug 30 21:37:21 volumiostreamer volumio[1054]: Error adding Membership: Error: addMembership EINVAL
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Shairport-Sync Started
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: Shairport-Sync Started
Aug 30 21:37:21 volumiostreamer volumio[1054]: error: Upnp client error: Error: read ECONNRESET
Aug 30 21:37:21 volumiostreamer volumio[1054]: error: Upnp client error: Error: read ECONNRESET
Aug 30 21:37:21 volumiostreamer sudo[1436]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer sudo[1436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer sudo[1438]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Aug 30 21:37:21 volumiostreamer sudo[1436]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:21 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:22 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:22 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:37:22 volumiostreamer volumio[1054]: info: Starting Shairport Sync
Aug 30 21:37:22 volumiostreamer sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 30 21:37:22 volumiostreamer sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:22 volumiostreamer volumio-remote-updater[588]: [2025-08-30 21:37:22] [connect] Successful connection
Aug 30 21:37:22 volumiostreamer volumio-remote-updater[588]: [2025-08-30 21:37:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756582642 101
Aug 30 21:37:22 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 30 21:37:22 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 30 21:37:22 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:22 volumiostreamer volumio[1054]: 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: 1
Aug 30 21:37:22 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 30 21:37:22 volumiostreamer sudo[1446]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:22 volumiostreamer volumio[1054]: info: Shairport-Sync Started
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: Discovery: adding 7477ff00-8360-44d7-bb0f-f0a16825bf78
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: Discovery: Found device Volumiostreamer
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:25 volumiostreamer systemd[1]: systemd-timedated.service: Deactivated successfully.
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: Discovery: this is already registered, 7477ff00-8360-44d7-bb0f-f0a16825bf78
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: Discovery: Found device Volumiostreamer
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:25 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:25 volumiostreamer volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Aug 30 21:37:25 volumiostreamer volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Aug 30 21:37:27 volumiostreamer mpd[1439]: 2025-08-30T21:37:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 30 21:37:27 volumiostreamer systemd[1]: Started mpd.service - Music Player Daemon.
Aug 30 21:37:27 volumiostreamer sudo[1396]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:27 volumiostreamer sudo[1295]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:27 volumiostreamer sudo[1309]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: Completed starting Core Plugins
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: -------------------------------------------
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: ----- MyVolumio plugins startup ----
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: -------------------------------------------
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 30 21:37:27 volumiostreamer volumio[1054]: info: MPD running with PID1439
Aug 30 21:37:27 volumiostreamer volumio[1054]: ,establishing connection
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: MPD error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: MPD error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: MPD error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: The expression evaluated to a falsy value:
Aug 30 21:37:27 volumiostreamer volumio[1054]: assert.ok(self.idling)
Aug 30 21:37:27 volumiostreamer volumio[1054]: error: updateQueue error: null
Aug 30 21:37:32 volumiostreamer volumio[1054]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Aug 30 21:37:35 volumiostreamer volumio[1054]: info: Discovery: this is already registered, 7477ff00-8360-44d7-bb0f-f0a16825bf78
Aug 30 21:37:35 volumiostreamer volumio[1054]: info: Discovery: Found device Volumiostreamer
Aug 30 21:37:35 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:35 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:35 volumiostreamer volumio[1054]: error: FusionDsp - Reload WebSocket error: [object Object]
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Discovery: this is already registered, 7477ff00-8360-44d7-bb0f-f0a16825bf78
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Discovery: Found device Volumiostreamer
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin multiroom to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 30 21:37:36 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: MyVolumio login type: Token
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 30 21:37:40 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 30 21:37:43 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 30 21:37:43 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 30 21:37:43 volumiostreamer volumio[1054]: info: Streaming services startup
Aug 30 21:37:43 volumiostreamer volumio[1054]: info: Starting Streaming Daemon
Aug 30 21:37:43 volumiostreamer volumio[1054]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 30 21:37:43 volumiostreamer sudo[1504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 30 21:37:43 volumiostreamer sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:43 volumiostreamer sudo[1504]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:43 volumiostreamer volumio[1054]: error: Cannot start Volumio Streaming Daemon
Aug 30 21:37:43 volumiostreamer volumio[1054]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 30 21:37:43 volumiostreamer volumio[1054]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 30 21:37:44 volumiostreamer volumio[1054]: error: MyVolumio Custom Token format not valid, refreshing it
Aug 30 21:37:45 volumiostreamer volumio[1054]: info: MyVolumio login type: Token
Aug 30 21:37:46 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 30 21:37:46 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:46 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 30 21:37:47 volumiostreamer volumio-remote-updater[588]: No test mode
Aug 30 21:37:47 volumiostreamer volumio-remote-updater[588]: Alpha test mode enabled
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"IMPROVEMENTS
\n\n- CD-ROM playback reliability improved using
cdspeedctl \n- MPD upgraded to version 0.24.5-2 for Volumio 4
\n
\nFIXES
\n\n- ALSA capture: Null dir response resolved
\n- Triggerhappy: Now runs in service-only mode with proper rebind
\n- SMB: Resolved a race condition regression
\n- Multiroom:\n
\n - Fixed use of undefined service variable
\n - Updated
volumio-alsa-plugin to 2.0.0 \n - Removed obsolete segment files
\n
\n \n- UPnP: Fixed device description templates and XML structure placeholders
\n- Backend: Applied workaround for
libxmljs breaking changes affecting WebRadio \n- SMB: Attempted protocol downgrade for better performance
\n
\n","title":"Update v4.018","updateavailable":true}
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: MyVolumio token set successfully
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: MYVOLUMIO: Adding device
Aug 30 21:37:47 volumiostreamer volumio[1054]: info: MYVOLUMIO: Evaluating Server
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: MyVolumio status changed
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Streaming services startup
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Starting Streaming Daemon
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Removing browser output: myVolumio user plan is not superstar
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Removing audio output:
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Stoppping Tunnel 1
Aug 30 21:37:48 volumiostreamer sudo[1546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 30 21:37:48 volumiostreamer sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:48 volumiostreamer sudo[1546]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:48 volumiostreamer volumio[1054]: error: Cannot start Volumio Streaming Daemon
Aug 30 21:37:48 volumiostreamer sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Aug 30 21:37:48 volumiostreamer volumio[1054]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 30 21:37:48 volumiostreamer volumio[1054]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 30 21:37:48 volumiostreamer sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 30 21:37:48 volumiostreamer sudo[1548]: pam_unix(sudo:session): session closed for user root
Aug 30 21:37:48 volumiostreamer volumio[1054]: info: Remote SSH Stopped
Aug 30 21:37:52 volumiostreamer volumio[1054]: info: Setting Geolocation for MyVolumio to eu4
Aug 30 21:37:52 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:52 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:52 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:53 volumiostreamer volumio[1054]: info: Updating MyVolumio device info
Aug 30 21:37:53 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:53 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:53 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 30 21:37:53 volumiostreamer volumio[1054]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Aug 30 21:37:53 volumiostreamer volumio[1054]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Aug 30 21:37:55 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 30 21:37:55 volumiostreamer volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Aug 30 21:37:55 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetState
Aug 30 21:37:55 volumiostreamer volumio[1054]: info: CorePlayQueue::getTrack 0
Aug 30 21:37:57 volumiostreamer volumio[1054]: info: MYVOLUMIO: Adding device
Aug 30 21:37:57 volumiostreamer volumio[1054]: info: MYVOLUMIO: Evaluating Server
Aug 30 21:37:58 volumiostreamer systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Aug 30 21:37:58 volumiostreamer dbus-daemon[570]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.28' (uid=0 pid=1581 comm="timedatectl show --property=NTPSynchronized --valu")
Aug 30 21:37:58 volumiostreamer systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Aug 30 21:37:58 volumiostreamer dbus-daemon[570]: [system] Successfully activated service 'org.freedesktop.timedate1'
Aug 30 21:37:58 volumiostreamer systemd[1]: Started systemd-timedated.service - Time & Date Service.
Aug 30 21:37:58 volumiostreamer setdatetime-helper.sh[1580]: Time is not synchronized. Attempting to sync...
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 30 21:38:00 volumiostreamer volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 30 21:38:02 volumiostreamer sudo[1589]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 30 Aug 2025 19:38:02 GMT#015'
Aug 30 21:38:02 volumiostreamer sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 30 21:38:02 volumiostreamer setdatetime-helper.sh[1590]: Sat Aug 30 21:38:02 CEST 2025
Aug 30 21:38:02 volumiostreamer systemd-journald[315]: Time jumped backwards, rotating.
Aug 30 21:38:02 volumiostreamer volumio[1054]: error: Failed to ping endpoint us3.myvolumio.org : unknown error
Aug 30 21:38:02 volumiostreamer sudo[1589]: pam_unix(sudo:session): session closed for user root
Aug 30 21:38:02 volumiostreamer setdatetime-helper.sh[1580]: Time synchronized successfully.
Aug 30 21:38:02 volumiostreamer systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Aug 30 21:38:02 volumiostreamer systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Aug 30 21:38:02 volumiostreamer volumio[1054]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 30 21:38:02 volumiostreamer volumio[1054]: Error: Unable to resolve or reject the same promise twice
Aug 30 21:38:02 volumiostreamer volumio[1054]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at Socket.emit (node:events:514:28)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at addChunk (node:internal/streams/readable:343:12)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at readableAddChunk (node:internal/streams/readable:316:9)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at Readable.push (node:internal/streams/readable:253:10)
Aug 30 21:38:02 volumiostreamer volumio[1054]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)
Aug 30 21:38:02 volumiostreamer volumio[1054]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 30 21:38:05 volumiostreamer sudo[1620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-30 21:37'
Aug 30 21:38:05 volumiostreamer sudo[1620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="26d819930af572ef184e636a18ef1127de78f391"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025"
VOLUMIO_VERSION="0.068"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"