Feb 06 22:25:06 volumio-digiamp volumio[1133]: info: Loading plugin "outputs"...
Feb 06 22:25:06 volumio-digiamp volumio[1133]: info: Loading plugin "albumart"...
Feb 06 22:25:06 volumio-digiamp volumio[1133]: info: Plugin example_plugin is not enabled
Feb 06 22:25:06 volumio-digiamp volumio[1133]: info: Loading plugin "inputs"...
Feb 06 22:25:06 volumio-digiamp volumio[1133]: info: Loading plugin "updater_comm"...
Feb 06 22:25:07 volumio-digiamp volumio[1133]: info: Plugin mpdemulation is not enabled
Feb 06 22:25:07 volumio-digiamp volumio[1133]: info: Loading plugin "rest_api"...
Feb 06 22:25:07 volumio-digiamp volumio[1133]: info: Loading plugin "websocket"...
Feb 06 22:25:07 volumio-digiamp volumio[1133]: info: Starting Socket.io Server version 1.7.4
Feb 06 22:25:07 volumio-digiamp volumio[1133]: info: Loading plugin "pandora"...
Feb 06 22:25:08 volumio-digiamp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5.
Feb 06 22:25:08 volumio-digiamp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:09 volumio-digiamp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:09 volumio-digiamp upmpdcli[1495]: Could not open config: /tmp/upmpdcli.conf
Feb 06 22:25:09 volumio-digiamp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 06 22:25:09 volumio-digiamp systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 06 22:25:09 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:25:09] [connect] Successful connection
Feb 06 22:25:09 volumio-digiamp volumio[1485]: Forking 3 albumart workers
Feb 06 22:25:24 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:25:24] [connect] Successful connection
Feb 06 22:25:24 volumio-digiamp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6.
Feb 06 22:25:24 volumio-digiamp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:24 volumio-digiamp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:24 volumio-digiamp upmpdcli[1540]: Could not open config: /tmp/upmpdcli.conf
Feb 06 22:25:24 volumio-digiamp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 06 22:25:24 volumio-digiamp systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 06 22:25:30 volumio-digiamp volumio[1498]: Starting albumart workers
Feb 06 22:25:30 volumio-digiamp volumio[1497]: Starting albumart workers
Feb 06 22:25:30 volumio-digiamp volumio[1496]: Starting albumart workers
Feb 06 22:25:30 volumio-digiamp volumio[1133]: info: Applying required configuration parameters for plugin pandora
Feb 06 22:25:33 volumio-digiamp volumio[1133]: info: Loading plugin "ir_controller"...
Feb 06 22:25:36 volumio-digiamp volumio[1133]: info: Applying required configuration parameters for plugin ir_controller
Feb 06 22:25:39 volumio-digiamp volumio[1133]: info: Loading plugin "rotaryencoder2"...
Feb 06 22:25:39 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:25:39] [connect] Successful connection
Feb 06 22:25:39 volumio-digiamp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7.
Feb 06 22:25:39 volumio-digiamp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:40 volumio-digiamp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:40 volumio-digiamp upmpdcli[1576]: Could not open config: /tmp/upmpdcli.conf
Feb 06 22:25:40 volumio-digiamp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 06 22:25:40 volumio-digiamp systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 06 22:25:41 volumio-digiamp kernel: w1_master_driver w1_bus_master1: Family 0 for 00.400000000000.46 is not registered.
Feb 06 22:25:45 volumio-digiamp volumio[1133]: info: Loading i18n strings for locale en
Feb 06 22:25:45 volumio-digiamp volumio[1133]: Updating browse sources language
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::initPlayerControls
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 06 22:25:46 volumio-digiamp volumio[1133]: Express server listening on port 3000
Feb 06 22:25:46 volumio-digiamp volumio[1133]: [Metrics] WebUI: 82s 940.62ms
Feb 06 22:25:47 volumio-digiamp volumio[1133]: info: CoreStateMachine::resetVolumioState
Feb 06 22:25:47 volumio-digiamp volumio[1133]: info: CoreStateMachine::getcurrentVolume
Feb 06 22:25:47 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume
Feb 06 22:25:47 volumio-digiamp sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 06 22:25:47 volumio-digiamp sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:47 volumio-digiamp sudo[1595]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:47 volumio-digiamp sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 06 22:25:47 volumio-digiamp sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:47 volumio-digiamp sudo[1597]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:47 volumio-digiamp volumio[1133]: info: Volumio Network Manager: Network status updated: 2
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: VolumeController:: Volume=69 Mute =false
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: CoreStateMachine::pushState
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 06 22:25:48 volumio-digiamp volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 06 22:25:49 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 06 22:25:49 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioRetrievevolume
Feb 06 22:25:49 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:49 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: Reloading queue from file
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreStateMachine::setRepeat null single undefined
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreStateMachine::pushState
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreStateMachine::setRandom null
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreStateMachine::pushState
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:50 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: Setting Device type: Raspberry PI
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: DAC script executed
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: Completed loading Core Plugins
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: Preparing to generate the ALSA configuration file
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: VolumeController:: Volume=69 Mute =false
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CoreStateMachine::pushState
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:51 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Discovery: adding ecf96541-27cb-444c-a3b1-96e6cdda1dcf
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Discovery: Found device Volumio-Digiamp
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:52 volumio-digiamp sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 06 22:25:52 volumio-digiamp sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Discovery: this is already registered, ecf96541-27cb-444c-a3b1-96e6cdda1dcf
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Discovery: Found device Volumio-Digiamp
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Asound.conf file unchanged, so no further update is needed
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Output device has changed, restarting MPD
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: Output device has changed, restarting Shairport Sync
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:52 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:25:53 volumio-digiamp sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 06 22:25:53 volumio-digiamp sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:53 volumio-digiamp sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 06 22:25:53 volumio-digiamp sudo[1636]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:53 volumio-digiamp sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: ___________ START PLUGINS ___________
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: ControllerMpd::onStart: Initializing MPD
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: Creating MPD Configuration file
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 06 22:25:53 volumio-digiamp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 06 22:25:53 volumio-digiamp systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: [1770438353699] CoreMusicLibrary::Adding element Media Servers
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:53 volumio-digiamp volumio[1133]: info: UPNP Browser: Client initialized successfully
Feb 06 22:25:53 volumio-digiamp sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 06 22:25:54 volumio-digiamp sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:54 volumio-digiamp sudo[1646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 06 22:25:54 volumio-digiamp sudo[1646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:25:54 volumio-digiamp sudo[1650]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 06 22:25:54 volumio-digiamp sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 06 22:25:54 volumio-digiamp sudo[1653]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 06 22:25:54 volumio-digiamp sudo[1650]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:54 volumio-digiamp sudo[1646]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:25:54 volumio-digiamp systemd[1]: mpd.service: Deactivated successfully.
Feb 06 22:25:54 volumio-digiamp systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 06 22:25:54 volumio-digiamp systemd[1]: mpd.socket: Deactivated successfully.
Feb 06 22:25:54 volumio-digiamp systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 06 22:25:54 volumio-digiamp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 06 22:25:54 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:25:54] [connect] Successful connection
Feb 06 22:25:54 volumio-digiamp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 06 22:25:54 volumio-digiamp systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:25:54 volumio-digiamp sudo[1660]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 06 22:25:54 volumio-digiamp sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: [1770438354880] CoreMusicLibrary::Adding element Last_100
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:54 volumio-digiamp sudo[1665]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 06 22:25:54 volumio-digiamp sudo[1660]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: [1770438354962] CoreMusicLibrary::Adding element Webradio
Feb 06 22:25:54 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:55 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 06 22:25:55 volumio-digiamp volumio[1133]: info: Initializing BBC Radios
Feb 06 22:25:55 volumio-digiamp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8.
Feb 06 22:25:55 volumio-digiamp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:55 volumio-digiamp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 06 22:25:55 volumio-digiamp sudo[1633]: pam_unix(sudo:session): session closed for user root
Feb 06 22:25:55 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 06 22:25:55 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: Creating Spotify config file
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: [1770438356386] CoreMusicLibrary::Adding element YouTube2
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:56 volumio-digiamp volumio[1133]: Cannot find translation for source YouTube2
Feb 06 22:25:56 volumio-digiamp volumio[1133]: info: [ytcr] Data store TTL expired - clearing it...
Feb 06 22:25:57 volumio-digiamp volumio[1133]: info: [now-playing] ConfigUpdater: config is up to date.
Feb 06 22:25:57 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 06 22:25:57 volumio-digiamp volumio[1133]: info: [1770438357832] CoreMusicLibrary::Adding element Pandora Radio
Feb 06 22:25:57 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 06 22:25:57 volumio-digiamp volumio[1133]: Cannot find translation for source YouTube2
Feb 06 22:25:57 volumio-digiamp volumio[1133]: Cannot find translation for source Pandora Radio
Feb 06 22:25:57 volumio-digiamp volumio[1133]: info: [1770438357914] ControllerPandora::initialSetup
Feb 06 22:25:58 volumio-digiamp volumio[1133]: info: [1770438358019] ControllerPandora::checkPing
Feb 06 22:25:58 volumio-digiamp volumio[1133]: info: Loading i18n strings for locale en
Feb 06 22:25:58 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 06 22:25:59 volumio-digiamp volumio[1133]: info: Volumio Calling Home
Feb 06 22:25:59 volumio-digiamp sudo[1706]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l
Feb 06 22:25:59 volumio-digiamp sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:00 volumio-digiamp sudo[1706]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:02 volumio-digiamp sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc
Feb 06 22:26:02 volumio-digiamp sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:02 volumio-digiamp sudo[1715]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:06 volumio-digiamp mpd[1666]: 2026-02-06T22:26:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 06 22:26:06 volumio-digiamp systemd[1]: Started mpd.service - Music Player Daemon.
Feb 06 22:26:06 volumio-digiamp sudo[1638]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:06 volumio-digiamp sudo[1649]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:07 volumio-digiamp volumio[1133]: info: [now-playing] App is listening on port 4004.
Feb 06 22:26:07 volumio-digiamp volumio[1133]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: Discovery: adding 1c722341-0eab-4e53-8de1-1239513ec7ea
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: Discovery: Found device raspdac
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: Discovery: Connecting to remote: 192.168.88.145
Feb 06 22:26:08 volumio-digiamp volumio[1133]: verbose: New Socket.io Connection to 192.168.88.52:3000 from 192.168.88.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: ir_controller: File permissions successfully set on /etc/lirc/*.
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: MPD Permissions set
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: MPD Permissions set
Feb 06 22:26:08 volumio-digiamp volumio[1133]: info: Upmpdcli Daemon Started
Feb 06 22:26:08 volumio-digiamp sudo[1737]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=24 relative_axis=true steps-per-period=1
Feb 06 22:26:08 volumio-digiamp sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:09 volumio-digiamp volumio[1133]: info: [1770438369080] [Pandora] checkPing: ***LOOKS GOOD, ICE DOWN THE BEERS IN THE COOLER***
Feb 06 22:26:09 volumio-digiamp volumio[1133]: info: [1770438369083] ControllerPandora::checkHTTP
Feb 06 22:26:09 volumio-digiamp sudo[1737]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:09 volumio-digiamp volumio[1133]: info: Spotify config file written
Feb 06 22:26:09 volumio-digiamp kernel: rotary-encoder rotary@17: gray
Feb 06 22:26:09 volumio-digiamp kernel: input: rotary@17 as /devices/platform/rotary@17/input/input0
Feb 06 22:26:09 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:26:09 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:26:09 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:26:09] [connect] Successful connection
Feb 06 22:26:09 volumio-digiamp sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Feb 06 22:26:09 volumio-digiamp sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:09 volumio-digiamp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 06 22:26:09 volumio-digiamp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 06 22:26:09 volumio-digiamp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 06 22:26:09 volumio-digiamp go-librespot[1755]: go-librespot daemon starting...
Feb 06 22:26:09 volumio-digiamp sudo[1753]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:10 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:10-06:00" level=info msg="running go-librespot 0.6.2"
Feb 06 22:26:10 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:10-06:00" level=debug msg="app state loaded"
Feb 06 22:26:10 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:10-06:00" level=debug msg="stored credentials not found"
Feb 06 22:26:10 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 06 22:26:11 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:11-06:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 06 22:26:11 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:11-06:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 06 22:26:11 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:11-06:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 06 22:26:11 volumio-digiamp go-librespot[1756]: time="2026-02-06T22:26:11-06:00" level=info msg="zeroconf server listening on port 35141"
Feb 06 22:26:12 volumio-digiamp volumio[1133]: error: MPD error: The expression evaluated to a falsy value:
Feb 06 22:26:12 volumio-digiamp volumio[1133]: assert.ok(self.idling)
Feb 06 22:26:12 volumio-digiamp volumio[1133]: error: The expression evaluated to a falsy value:
Feb 06 22:26:12 volumio-digiamp volumio[1133]: assert.ok(self.idling)
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: MPD running with PID1666
Feb 06 22:26:13 volumio-digiamp volumio[1133]: ,establishing connection
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: Volumio called home
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: No need to fix Spotify hosts
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:26:13 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:26:15 volumio-digiamp volumio[1133]: error: updateQueue error: null
Feb 06 22:26:16 volumio-digiamp sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=27 active_low=true gpio_pull=up keycode=20
Feb 06 22:26:16 volumio-digiamp sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:16 volumio-digiamp kernel: input: button@1b as /devices/platform/button@1b/input/input1
Feb 06 22:26:16 volumio-digiamp sudo[1778]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:16 volumio-digiamp volumio[1133]: info: Discovery: Connected to remote: 192.168.88.145
Feb 06 22:26:17 volumio-digiamp volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 06 22:26:17 volumio-digiamp volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 06 22:26:17 volumio-digiamp volumio[1133]: error: updateQueue error: null
Feb 06 22:26:21 volumio-digiamp volumio[1133]: verbose: New Socket.io Connection to 192.168.88.52:3000 from 192.168.88.126 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2
Feb 06 22:26:22 volumio-digiamp volumio[1133]: info: go-librespot daemon successfully initialized
Feb 06 22:26:22 volumio-digiamp volumio[1133]: error: [ROTARYENCODER2] Socket connection error: undefined
Feb 06 22:26:22 volumio-digiamp volumio[1133]: info: Starting Shairport Sync
Feb 06 22:26:22 volumio-digiamp volumio[1133]: info: Starting Shairport Sync
Feb 06 22:26:22 volumio-digiamp volumio[1133]: info: Starting Shairport Sync
Feb 06 22:26:22 volumio-digiamp sudo[1803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 06 22:26:22 volumio-digiamp sudo[1803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:22 volumio-digiamp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 06 22:26:22 volumio-digiamp systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 06 22:26:22 volumio-digiamp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:22 volumio-digiamp systemd[1]: shairport-sync.service: Consumed 3.356s CPU time.
Feb 06 22:26:23 volumio-digiamp sudo[1808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 06 22:26:23 volumio-digiamp sudo[1808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:23 volumio-digiamp sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 06 22:26:23 volumio-digiamp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:23 volumio-digiamp sudo[1803]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:23 volumio-digiamp sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:23 volumio-digiamp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 06 22:26:23 volumio-digiamp systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 06 22:26:23 volumio-digiamp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:23 volumio-digiamp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:23 volumio-digiamp sudo[1808]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:23 volumio-digiamp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 06 22:26:23 volumio-digiamp systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 06 22:26:23 volumio-digiamp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:23 volumio-digiamp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 06 22:26:23 volumio-digiamp sudo[1805]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:24 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:26:24] [connect] Successful connection
Feb 06 22:26:28 volumio-digiamp volumio[1133]: info: Initializing connection to go-librespot Websocket
Feb 06 22:26:28 volumio-digiamp volumio[1133]: info: [1770438388691] [Pandora] checkHTTP: Ping works but web requests are not working. Still waiting....
Feb 06 22:26:29 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 06 22:26:29 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 06 22:26:29 volumio-digiamp volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 06 22:26:29 volumio-digiamp sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l
Feb 06 22:26:29 volumio-digiamp sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 06 22:26:29 volumio-digiamp sudo[1842]: pam_unix(sudo:session): session closed for user root
Feb 06 22:26:29 volumio-digiamp volumio[1133]: info: ir_controller: LIRC correctly updated.
Feb 06 22:26:29 volumio-digiamp volumio[1133]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver!
Feb 06 22:26:29 volumio-digiamp volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 06 22:26:29 volumio-digiamp volumio[1133]: Error: Unable to resolve or reject the same promise twice
Feb 06 22:26:29 volumio-digiamp volumio[1133]: at Promise.resolve (/data/plugins/system_hardware/rotaryencoder2/node_modules/kew/kew.js:140:43)
Feb 06 22:26:29 volumio-digiamp volumio[1133]: at /data/plugins/system_hardware/rotaryencoder2/index.js:1017:23
Feb 06 22:26:29 volumio-digiamp volumio[1133]: at FSReqCallback.oncomplete (node:fs:190:23)
Feb 06 22:26:29 volumio-digiamp volumio[1133]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 06 22:26:31 volumio-digiamp kernel: w1_master_driver w1_bus_master1: Family 0 for 00.c00000000000.ca is not registered.
Feb 06 22:26:39 volumio-digiamp volumio-remote-updater[641]: [2026-02-06 22:26:39] [connect] Successful connection
Feb 06 22:26:41 volumio-digiamp sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-06 22:25'
Feb 06 22:26:41 volumio-digiamp sudo[1893]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"