-- Logs begin at Tue 2025-12-16 22:08:29 CET, end at Tue 2025-12-16 22:11:08 CET. -- Dec 16 22:10:00 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:00 volumio-jan mpd[2221]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:00 volumio-jan mpd[2221]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:00 volumio-jan mpd[2221]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:00 volumio-jan sudo[2264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:00 volumio-jan sudo[2264]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:00 volumio-jan sudo[2264]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:00 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:00 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:00 volumio-jan ntfs-3g[2124]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:00 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:00 volumio-jan sudo[2202]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:00 volumio-jan sudo[2233]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:00 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:00 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:00 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:00 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:00 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 16 22:10:00 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:00 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:00 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:00 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:01 volumio-jan volumio[2278]: info: ------------------------------------------- Dec 16 22:10:01 volumio-jan volumio[2278]: info: ----- Volumio3 ---- Dec 16 22:10:01 volumio-jan volumio[2278]: info: ------------------------------------------- Dec 16 22:10:01 volumio-jan volumio[2278]: info: ----- System startup ---- Dec 16 22:10:01 volumio-jan volumio[2278]: info: ------------------------------------------- Dec 16 22:10:02 volumio-jan volumio[2278]: info: MYVOLUMIO Environment detected Dec 16 22:10:02 volumio-jan volumio[2278]: info: Plugin folders cleanup Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category audio_interface Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category miscellanea Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category music_service Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category plugins.json Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category system_controller Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category user_interface Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning into folder /data/plugins/ Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category miscellanea Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category system_controller Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category system_hardware Dec 16 22:10:02 volumio-jan volumio[2278]: info: Scanning category user_interface Dec 16 22:10:02 volumio-jan volumio[2278]: info: Plugin folders cleanup completed Dec 16 22:10:02 volumio-jan volumio[2278]: info: ------------------------------------------- Dec 16 22:10:02 volumio-jan volumio[2278]: info: ----- Core plugins startup ---- Dec 16 22:10:02 volumio-jan volumio[2278]: info: ------------------------------------------- Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:02 volumio-jan volumio[2278]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:02 volumio-jan volumio[2278]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:02 volumio-jan volumio[2278]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugin "system"... Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugin "appearance"... Dec 16 22:10:02 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:02] [connect] Successful connection Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugin "network"... Dec 16 22:10:02 volumio-jan volumio[2278]: info: Refreshing Cached IP Addresses Dec 16 22:10:02 volumio-jan sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:02 volumio-jan sudo[2302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:02 volumio-jan sudo[2302]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:02 volumio-jan sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:02 volumio-jan sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:02 volumio-jan sudo[2304]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:02 volumio-jan volumio[2278]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:02 volumio-jan sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:02 volumio-jan sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:03 volumio-jan volumio[2278]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "services"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "alsa_controller"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "wizard"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "upnp"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: [1765919403236] Starting Upmpd Daemon Dec 16 22:10:03 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "my_music"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "mpd"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Creating MPD Configuration file Dec 16 22:10:03 volumio-jan sudo[2315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "upnp_browser"... Dec 16 22:10:03 volumio-jan sudo[2315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:03 volumio-jan sudo[2315]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:03 volumio-jan sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:03 volumio-jan sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:03 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:03 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:03 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:03 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Loading plugin "networkfs"... Dec 16 22:10:03 volumio-jan volumio[2278]: info: Starting Udev Watcher for removable devices Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:04 volumio-jan volumio[2278]: error: Cannot associate FS Label, not mounting Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: boot Dec 16 22:10:04 volumio-jan volumio[2278]: info: Ignoring mount for partition: volumio Dec 16 22:10:04 volumio-jan volumio[2278]: info: Mounting Device PENDRIVE Dec 16 22:10:04 volumio-jan mpd[2322]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:04 volumio-jan sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:04 volumio-jan sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:04 volumio-jan sudo[2340]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:04 volumio-jan volumio[2278]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:04 volumio-jan volumio[2278]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:04 volumio-jan volumio[2278]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:04 volumio-jan volumio[2278]: info: Mounting Device MyBook Dec 16 22:10:04 volumio-jan mpd[2322]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:04 volumio-jan mpd[2322]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:04 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:04 volumio-jan sudo[2317]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:04 volumio-jan sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:04 volumio-jan sudo[2346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:05 volumio-jan ntfs-3g[2352]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:05 volumio-jan ntfs-3g[2352]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:05 volumio-jan ntfs-3g[2352]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:05 volumio-jan ntfs-3g[2352]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:05 volumio-jan ntfs-3g[2352]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:05 volumio-jan sudo[2346]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:05 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "alarm-clock"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Starting Shairport Sync Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "last_100"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "webradio"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** For more information see Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:05 volumio-jan volumio[2278]: *** WARNING *** For more information see Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** For more information see Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:05 volumio-jan node[2278]: *** WARNING *** For more information see Dec 16 22:10:05 volumio-jan volumio[2278]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:05 volumio-jan volumio[2278]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:05 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:05 volumio-jan volumio[2278]: info: Plugin autostart is not enabled Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "now_playing"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "outputs"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "albumart"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Plugin example_plugin is not enabled Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "inputs"... Dec 16 22:10:05 volumio-jan volumio[2278]: info: Loading plugin "updater_comm"... Dec 16 22:10:06 volumio-jan volumio[2278]: info: Plugin mpdemulation is not enabled Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading plugin "rest_api"... Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading plugin "websocket"... Dec 16 22:10:06 volumio-jan volumio[2278]: info: Plugin gpio_control is not enabled Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:06 volumio-jan volumio[2278]: Forking 3 albumart workers Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading plugin "lastfm"... Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading plugin "touch_display"... Dec 16 22:10:06 volumio-jan volumio[2278]: Starting albumart workers Dec 16 22:10:06 volumio-jan volumio[2278]: Starting albumart workers Dec 16 22:10:06 volumio-jan volumio[2278]: Starting albumart workers Dec 16 22:10:06 volumio-jan volumio[2278]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:06 volumio-jan volumio[2278]: info: Loading i18n strings for locale it Dec 16 22:10:06 volumio-jan volumio[2278]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:06 volumio-jan volumio[2278]: Updating browse sources language Dec 16 22:10:06 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: Express server listening on port 3000 Dec 16 22:10:07 volumio-jan volumio[2278]: [Metrics] WebUI: 5s 658.65ms Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: MPD Permissions set Dec 16 22:10:07 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919402 101 Dec 16 22:10:07 volumio-jan volumio[2278]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:07 volumio-jan volumio[2278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:07 volumio-jan volumio[2278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:07 volumio-jan volumio[2278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:07 volumio-jan volumio[2278]: info: MPD running with PID2322 Dec 16 22:10:07 volumio-jan volumio[2278]: ,establishing connection Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: Reloading queue from file Dec 16 22:10:07 volumio-jan volumio[2278]: error: updateQueue error: null Dec 16 22:10:07 volumio-jan volumio[2278]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::pushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::pushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::setRandom false Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::pushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: Setting Device type: Raspberry PI Dec 16 22:10:07 volumio-jan volumio[2278]: error: updateQueue error: null Dec 16 22:10:07 volumio-jan volumio[2278]: info: Completed loading Core Plugins Dec 16 22:10:07 volumio-jan volumio[2278]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:07 volumio-jan volumio[2278]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreStateMachine::pushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:07 volumio-jan volumio[2278]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:07 volumio-jan volumio[2278]: info: Reading ALSA contributions from plugins. Dec 16 22:10:07 volumio-jan volumio[2278]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:07 volumio-jan volumio[2278]: info: Output device has changed, restarting MPD Dec 16 22:10:07 volumio-jan sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:07 volumio-jan sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:07 volumio-jan volumio[2278]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan sudo[2426]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:07 volumio-jan sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:07 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:07 volumio-jan volumio[2278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: ___________ START PLUGINS ___________ Dec 16 22:10:07 volumio-jan volumio[2278]: info: Loading i18n strings for locale it Dec 16 22:10:07 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:07 volumio-jan volumio[2278]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:07 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:07 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:07 volumio-jan volumio[2278]: info: /tmp/myfifo created Dec 16 22:10:07 volumio-jan sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:07 volumio-jan sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:07 volumio-jan sudo[2438]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:07 volumio-jan volumio[2278]: info: snd-dummy loaded Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan systemd[1]: nmbd.service: Start operation timed out. Terminating. Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:07 volumio-jan systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Dec 16 22:10:07 volumio-jan systemd[1]: nmbd.service: Failed with result 'timeout'. Dec 16 22:10:07 volumio-jan systemd[1]: Failed to start Samba NMB Daemon. Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:07 volumio-jan volumio[2278]: info: [1765919407825] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:07 volumio-jan systemd[1]: Starting Samba Winbind Daemon... Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:07 volumio-jan volumio[2278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:07 volumio-jan volumio[2278]: info: [1765919407938] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:07 volumio-jan volumio[2278]: info: [1765919407940] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:07 volumio-jan volumio[2278]: info: [now-playing-config] Config is up to date. Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:07 volumio-jan volumio[2278]: info: Discovery: Getting this device information Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:07 volumio-jan volumio[2278]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:07 volumio-jan volumio[2278]: info: [1765919407991] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:07 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:07 volumio-jan volumio[2278]: Cannot find translation for source LastFM Dec 16 22:10:07 volumio-jan volumio[2278]: info: [LastFM] scrobbler initiated! Dec 16 22:10:07 volumio-jan volumio[2278]: info: [LastFM] extended logging: false Dec 16 22:10:07 volumio-jan volumio[2278]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:07 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Vodafone-JAN-NET" Dec 16 22:10:07 volumio-jan wpa_supplicant[771]: wlan0: Trying to associate with SSID 'Vodafone-JAN-NET' Dec 16 22:10:08 volumio-jan volumio[2278]: info: [LastFM] Left init routine Dec 16 22:10:08 volumio-jan volumio[2278]: info: [LastFM] Socket already connected: true Dec 16 22:10:08 volumio-jan volumio[2278]: info: Loading i18n strings for locale it Dec 16 22:10:08 volumio-jan volumio[2278]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:08 volumio-jan volumio[2278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:08 volumio-jan volumio[2278]: info: Volumio Calling Home Dec 16 22:10:08 volumio-jan volumio[2278]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:08 volumio-jan winbindd[2445]: [2025/12/16 22:10:08.051398, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 16 22:10:08 volumio-jan winbindd[2445]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 16 22:10:08 volumio-jan sudo[2308]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:08 volumio-jan winbindd[2445]: [2025/12/16 22:10:08.068987, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 22:10:08 volumio-jan winbindd[2445]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 16 22:10:08 volumio-jan systemd[1]: Started Samba Winbind Daemon. Dec 16 22:10:08 volumio-jan systemd[1]: Starting Samba SMB Daemon... Dec 16 22:10:08 volumio-jan volumio[2278]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:08 volumio-jan sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:08 volumio-jan sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:08 volumio-jan sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:08 volumio-jan sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:08 volumio-jan systemd[1]: Reloading. Dec 16 22:10:08 volumio-jan sudo[2461]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:08 volumio-jan volumio[2278]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:08 volumio-jan volumio[2278]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:08 volumio-jan volumio[2278]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:08 volumio-jan volumio[2278]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:08 volumio-jan volumio[2278]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:08 volumio-jan volumio[2278]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:08 volumio-jan volumio[2278]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:08 volumio-jan volumio[2278]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:08 volumio-jan volumio[2278]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:08 volumio-jan volumio[2278]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:08 volumio-jan volumio[2278]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:08 volumio-jan volumio[2278]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:08 volumio-jan volumio[2278]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:08 volumio-jan volumio[2278]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:08 volumio-jan volumio[2278]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:08 volumio-jan volumio[2278]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:08 volumio-jan volumio[2278]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:08 volumio-jan volumio[2278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:08 volumio-jan volumio[2278]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:08 volumio-jan volumio[2278]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:08 volumio-jan volumio[2278]: errno: -16, Dec 16 22:10:08 volumio-jan volumio[2278]: code: 'EBUSY', Dec 16 22:10:08 volumio-jan volumio[2278]: syscall: 'getaddrinfo', Dec 16 22:10:08 volumio-jan volumio[2278]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:08 volumio-jan volumio[2278]: } Dec 16 22:10:08 volumio-jan volumio[2278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:08 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:08 volumio-jan smbd[2459]: [2025/12/16 22:10:08.640775, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 22:10:08 volumio-jan smbd[2459]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 16 22:10:08 volumio-jan mpd[2450]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:08 volumio-jan mpd[2450]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:08 volumio-jan mpd[2450]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:08 volumio-jan sudo[2503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:08 volumio-jan sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:09 volumio-jan sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:09 volumio-jan systemd[1]: Started Samba SMB Daemon. Dec 16 22:10:09 volumio-jan sudo[2463]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:09 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:09 volumio-jan sudo[2429]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:09 volumio-jan systemd[1]: Reached target Multi-User System. Dec 16 22:10:09 volumio-jan systemd[1]: Reached target Graphical Interface. Dec 16 22:10:09 volumio-jan systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 16 22:10:09 volumio-jan systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 16 22:10:09 volumio-jan systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 16 22:10:09 volumio-jan systemd[1]: Startup finished in 9.984s (kernel) + 1min 41.951s (userspace) = 1min 51.936s. Dec 16 22:10:09 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:09 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:09 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:09 volumio-jan ntfs-3g[2352]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:09 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:09 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:09 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:09 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:09 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:09 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 16 22:10:09 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:09 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:09 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:09 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:10 volumio-jan volumio[2519]: info: ------------------------------------------- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ----- Volumio3 ---- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ------------------------------------------- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ----- System startup ---- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ------------------------------------------- Dec 16 22:10:10 volumio-jan volumio[2519]: info: MYVOLUMIO Environment detected Dec 16 22:10:10 volumio-jan volumio[2519]: info: Plugin folders cleanup Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category audio_interface Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category miscellanea Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category music_service Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category plugins.json Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category system_controller Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category user_interface Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning into folder /data/plugins/ Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category miscellanea Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category system_controller Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category system_hardware Dec 16 22:10:10 volumio-jan volumio[2519]: info: Scanning category user_interface Dec 16 22:10:10 volumio-jan volumio[2519]: info: Plugin folders cleanup completed Dec 16 22:10:10 volumio-jan volumio[2519]: info: ------------------------------------------- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ----- Core plugins startup ---- Dec 16 22:10:10 volumio-jan volumio[2519]: info: ------------------------------------------- Dec 16 22:10:10 volumio-jan volumio[2519]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:10 volumio-jan volumio[2519]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:10 volumio-jan volumio[2519]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:10 volumio-jan volumio[2519]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:10 volumio-jan volumio[2519]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:10 volumio-jan volumio[2519]: info: Loading plugin "system"... Dec 16 22:10:10 volumio-jan volumio[2519]: info: Loading plugin "appearance"... Dec 16 22:10:10 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 16 22:10:10 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Vodafone-JAN-NET" auth_failures=3 duration=46 reason=CONN_FAILED Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "network"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: Refreshing Cached IP Addresses Dec 16 22:10:11 volumio-jan sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:11 volumio-jan sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:11 volumio-jan sudo[2542]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:11 volumio-jan sudo[2544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:11 volumio-jan sudo[2544]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:11 volumio-jan sudo[2544]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:11 volumio-jan sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:11 volumio-jan sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:11 volumio-jan volumio[2519]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "services"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "alsa_controller"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "wizard"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "upnp"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: [1765919411927] Starting Upmpd Daemon Dec 16 22:10:11 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "my_music"... Dec 16 22:10:11 volumio-jan volumio[2519]: info: Loading plugin "mpd"... Dec 16 22:10:12 volumio-jan volumio[2519]: info: Creating MPD Configuration file Dec 16 22:10:12 volumio-jan volumio[2519]: info: Loading plugin "upnp_browser"... Dec 16 22:10:12 volumio-jan sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:12 volumio-jan sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:12 volumio-jan sudo[2555]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:12 volumio-jan sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:12 volumio-jan sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:12 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:12 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:12 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:12 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:12 volumio-jan volumio[2519]: info: Loading plugin "networkfs"... Dec 16 22:10:12 volumio-jan volumio[2519]: info: Starting Udev Watcher for removable devices Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:12 volumio-jan volumio[2519]: error: Cannot associate FS Label, not mounting Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: boot Dec 16 22:10:12 volumio-jan volumio[2519]: info: Ignoring mount for partition: volumio Dec 16 22:10:12 volumio-jan mpd[2562]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:12 volumio-jan volumio[2519]: info: Mounting Device PENDRIVE Dec 16 22:10:12 volumio-jan sudo[2580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:12 volumio-jan sudo[2580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:12 volumio-jan sudo[2580]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:12 volumio-jan volumio[2519]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:12 volumio-jan volumio[2519]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:12 volumio-jan volumio[2519]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:12 volumio-jan volumio[2519]: info: Mounting Device MyBook Dec 16 22:10:13 volumio-jan mpd[2562]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:13 volumio-jan mpd[2562]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:13 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:13 volumio-jan sudo[2557]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:13 volumio-jan sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:13 volumio-jan sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:13 volumio-jan ntfs-3g[2591]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:13 volumio-jan ntfs-3g[2591]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:13 volumio-jan ntfs-3g[2591]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:13 volumio-jan ntfs-3g[2591]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:13 volumio-jan ntfs-3g[2591]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:13 volumio-jan sudo[2586]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:13 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "alarm-clock"... Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:13 volumio-jan volumio[2519]: info: Starting Shairport Sync Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "last_100"... Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "webradio"... Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:13 volumio-jan volumio[2519]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** For more information see Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:13 volumio-jan volumio[2519]: *** WARNING *** For more information see Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** For more information see Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:13 volumio-jan node[2519]: *** WARNING *** For more information see Dec 16 22:10:13 volumio-jan volumio[2519]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:13 volumio-jan volumio[2519]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:13 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:13 volumio-jan volumio[2519]: info: Plugin autostart is not enabled Dec 16 22:10:13 volumio-jan volumio[2519]: info: Loading plugin "now_playing"... Dec 16 22:10:14 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:14] [connect] Successful connection Dec 16 22:10:14 volumio-jan sudo[2548]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "outputs"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "albumart"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Plugin example_plugin is not enabled Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "inputs"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "updater_comm"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Plugin mpdemulation is not enabled Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "rest_api"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "websocket"... Dec 16 22:10:14 volumio-jan volumio[2519]: info: Plugin gpio_control is not enabled Dec 16 22:10:14 volumio-jan volumio[2519]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:14 volumio-jan volumio[2519]: Forking 3 albumart workers Dec 16 22:10:15 volumio-jan volumio[2519]: info: Loading plugin "lastfm"... Dec 16 22:10:15 volumio-jan volumio[2519]: info: Loading plugin "touch_display"... Dec 16 22:10:15 volumio-jan volumio[2519]: Starting albumart workers Dec 16 22:10:15 volumio-jan volumio[2519]: Starting albumart workers Dec 16 22:10:15 volumio-jan volumio[2519]: Starting albumart workers Dec 16 22:10:15 volumio-jan volumio[2519]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:15 volumio-jan volumio[2519]: info: Loading i18n strings for locale it Dec 16 22:10:15 volumio-jan volumio[2519]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:15 volumio-jan volumio[2519]: Updating browse sources language Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: Express server listening on port 3000 Dec 16 22:10:15 volumio-jan volumio[2519]: [Metrics] WebUI: 5s 666.63ms Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:15 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:15 volumio-jan volumio[2519]: info: MPD Permissions set Dec 16 22:10:15 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919414 101 Dec 16 22:10:15 volumio-jan volumio[2519]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:16 volumio-jan volumio[2519]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:16 volumio-jan volumio[2519]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:16 volumio-jan volumio[2519]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:16 volumio-jan volumio[2519]: info: MPD running with PID2562 Dec 16 22:10:16 volumio-jan volumio[2519]: ,establishing connection Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: Reloading queue from file Dec 16 22:10:16 volumio-jan volumio[2519]: error: updateQueue error: null Dec 16 22:10:16 volumio-jan volumio[2519]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::pushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::pushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::setRandom false Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::pushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: Setting Device type: Raspberry PI Dec 16 22:10:16 volumio-jan volumio[2519]: error: updateQueue error: null Dec 16 22:10:16 volumio-jan volumio[2519]: info: Completed loading Core Plugins Dec 16 22:10:16 volumio-jan volumio[2519]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:16 volumio-jan volumio[2519]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreStateMachine::pushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:16 volumio-jan volumio[2519]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:16 volumio-jan volumio[2519]: info: Reading ALSA contributions from plugins. Dec 16 22:10:16 volumio-jan volumio[2519]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:16 volumio-jan volumio[2519]: info: Output device has changed, restarting MPD Dec 16 22:10:16 volumio-jan sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:16 volumio-jan sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:16 volumio-jan volumio[2519]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:16 volumio-jan sudo[2666]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:16 volumio-jan sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:16 volumio-jan sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:16 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:16 volumio-jan volumio[2519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: ___________ START PLUGINS ___________ Dec 16 22:10:16 volumio-jan volumio[2519]: info: Loading i18n strings for locale it Dec 16 22:10:16 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:16 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:16 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:16 volumio-jan volumio[2519]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:16 volumio-jan volumio[2519]: info: /tmp/myfifo created Dec 16 22:10:16 volumio-jan sudo[2678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:16 volumio-jan sudo[2678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:16 volumio-jan sudo[2678]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:16 volumio-jan volumio[2519]: info: snd-dummy loaded Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:16 volumio-jan volumio[2519]: info: [1765919416527] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:16 volumio-jan volumio[2519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:16 volumio-jan volumio[2519]: info: [1765919416622] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:16 volumio-jan volumio[2519]: info: [1765919416625] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:16 volumio-jan volumio[2519]: info: [now-playing-config] Config is up to date. Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:16 volumio-jan volumio[2519]: info: Discovery: Getting this device information Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:16 volumio-jan volumio[2519]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:16 volumio-jan volumio[2519]: info: [1765919416671] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:16 volumio-jan volumio[2519]: Cannot find translation for source LastFM Dec 16 22:10:16 volumio-jan volumio[2519]: info: [LastFM] scrobbler initiated! Dec 16 22:10:16 volumio-jan volumio[2519]: info: [LastFM] extended logging: false Dec 16 22:10:16 volumio-jan volumio[2519]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:16 volumio-jan volumio[2519]: info: [LastFM] Left init routine Dec 16 22:10:16 volumio-jan volumio[2519]: info: [LastFM] Socket already connected: true Dec 16 22:10:16 volumio-jan volumio[2519]: info: Loading i18n strings for locale it Dec 16 22:10:16 volumio-jan volumio[2519]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:16 volumio-jan volumio[2519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:16 volumio-jan volumio[2519]: info: Volumio Calling Home Dec 16 22:10:16 volumio-jan volumio[2519]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:16 volumio-jan volumio[2519]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:16 volumio-jan sudo[2696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:16 volumio-jan sudo[2696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:16 volumio-jan sudo[2699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:16 volumio-jan sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:16 volumio-jan sudo[2696]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:16 volumio-jan systemd[1]: Reloading. Dec 16 22:10:16 volumio-jan volumio[2519]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:16 volumio-jan volumio[2519]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:16 volumio-jan volumio[2519]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:16 volumio-jan volumio[2519]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:16 volumio-jan volumio[2519]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:16 volumio-jan volumio[2519]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:16 volumio-jan volumio[2519]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:16 volumio-jan volumio[2519]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:16 volumio-jan volumio[2519]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:16 volumio-jan volumio[2519]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:16 volumio-jan volumio[2519]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:16 volumio-jan volumio[2519]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:16 volumio-jan volumio[2519]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:16 volumio-jan volumio[2519]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:16 volumio-jan volumio[2519]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:16 volumio-jan volumio[2519]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:16 volumio-jan volumio[2519]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:16 volumio-jan volumio[2519]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:16 volumio-jan volumio[2519]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:16 volumio-jan volumio[2519]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:16 volumio-jan volumio[2519]: errno: -16, Dec 16 22:10:16 volumio-jan volumio[2519]: code: 'EBUSY', Dec 16 22:10:16 volumio-jan volumio[2519]: syscall: 'getaddrinfo', Dec 16 22:10:16 volumio-jan volumio[2519]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:16 volumio-jan volumio[2519]: } Dec 16 22:10:16 volumio-jan volumio[2519]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:17 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:17 volumio-jan mpd[2688]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:17 volumio-jan mpd[2688]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:17 volumio-jan mpd[2688]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:17 volumio-jan sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:17 volumio-jan sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:17 volumio-jan sudo[2731]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:17 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:17 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:17 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:17 volumio-jan sudo[2699]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:17 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:17 volumio-jan ntfs-3g[2591]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:17 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:17 volumio-jan sudo[2669]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:17 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:17 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:17 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:17 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:17 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Dec 16 22:10:17 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:17 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:17 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:17 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:18 volumio-jan volumio[2746]: info: ------------------------------------------- Dec 16 22:10:18 volumio-jan volumio[2746]: info: ----- Volumio3 ---- Dec 16 22:10:18 volumio-jan volumio[2746]: info: ------------------------------------------- Dec 16 22:10:18 volumio-jan volumio[2746]: info: ----- System startup ---- Dec 16 22:10:18 volumio-jan volumio[2746]: info: ------------------------------------------- Dec 16 22:10:19 volumio-jan volumio[2746]: info: MYVOLUMIO Environment detected Dec 16 22:10:19 volumio-jan volumio[2746]: info: Plugin folders cleanup Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category audio_interface Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category miscellanea Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category music_service Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category plugins.json Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category system_controller Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category user_interface Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning into folder /data/plugins/ Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category miscellanea Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category system_controller Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category system_hardware Dec 16 22:10:19 volumio-jan volumio[2746]: info: Scanning category user_interface Dec 16 22:10:19 volumio-jan volumio[2746]: info: Plugin folders cleanup completed Dec 16 22:10:19 volumio-jan volumio[2746]: info: ------------------------------------------- Dec 16 22:10:19 volumio-jan volumio[2746]: info: ----- Core plugins startup ---- Dec 16 22:10:19 volumio-jan volumio[2746]: info: ------------------------------------------- Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:19 volumio-jan volumio[2746]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:19 volumio-jan volumio[2746]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:19 volumio-jan volumio[2746]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugin "system"... Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugin "appearance"... Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugin "network"... Dec 16 22:10:19 volumio-jan volumio[2746]: info: Refreshing Cached IP Addresses Dec 16 22:10:19 volumio-jan sudo[2769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:19 volumio-jan sudo[2769]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:19 volumio-jan sudo[2769]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:19 volumio-jan sudo[2771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:19 volumio-jan sudo[2771]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:19 volumio-jan volumio[2746]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:19 volumio-jan sudo[2771]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:19 volumio-jan sudo[2774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:19 volumio-jan sudo[2774]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:20 volumio-jan volumio[2746]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "services"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "alsa_controller"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "wizard"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "upnp"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: [1765919420210] Starting Upmpd Daemon Dec 16 22:10:20 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "my_music"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "mpd"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Creating MPD Configuration file Dec 16 22:10:20 volumio-jan sudo[2782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "upnp_browser"... Dec 16 22:10:20 volumio-jan sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:20 volumio-jan sudo[2782]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:20 volumio-jan sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:20 volumio-jan sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:20 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:20 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:20 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:20 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Loading plugin "networkfs"... Dec 16 22:10:20 volumio-jan volumio[2746]: info: Starting Udev Watcher for removable devices Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:21 volumio-jan volumio[2746]: error: Cannot associate FS Label, not mounting Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: boot Dec 16 22:10:21 volumio-jan volumio[2746]: info: Ignoring mount for partition: volumio Dec 16 22:10:21 volumio-jan volumio[2746]: info: Mounting Device PENDRIVE Dec 16 22:10:21 volumio-jan mpd[2789]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:21 volumio-jan sudo[2807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:21 volumio-jan sudo[2807]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:21 volumio-jan sudo[2807]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:21 volumio-jan volumio[2746]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:21 volumio-jan volumio[2746]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:21 volumio-jan volumio[2746]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:21 volumio-jan volumio[2746]: info: Mounting Device MyBook Dec 16 22:10:21 volumio-jan mpd[2789]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:21 volumio-jan mpd[2789]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:21 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:21 volumio-jan sudo[2784]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:21 volumio-jan sudo[2813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:21 volumio-jan sudo[2813]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:22 volumio-jan ntfs-3g[2819]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:22 volumio-jan ntfs-3g[2819]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:22 volumio-jan ntfs-3g[2819]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:22 volumio-jan ntfs-3g[2819]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:22 volumio-jan ntfs-3g[2819]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:22 volumio-jan sudo[2813]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:22 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "alarm-clock"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Starting Shairport Sync Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "last_100"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "webradio"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** For more information see Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:22 volumio-jan volumio[2746]: *** WARNING *** For more information see Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** For more information see Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:22 volumio-jan node[2746]: *** WARNING *** For more information see Dec 16 22:10:22 volumio-jan volumio[2746]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:22 volumio-jan volumio[2746]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:22 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:22 volumio-jan volumio[2746]: info: Plugin autostart is not enabled Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "now_playing"... Dec 16 22:10:22 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:22] [connect] Successful connection Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "outputs"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "albumart"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Plugin example_plugin is not enabled Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "inputs"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "updater_comm"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Plugin mpdemulation is not enabled Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "rest_api"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "websocket"... Dec 16 22:10:22 volumio-jan volumio[2746]: info: Plugin gpio_control is not enabled Dec 16 22:10:22 volumio-jan volumio[2746]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:23 volumio-jan volumio[2746]: Forking 3 albumart workers Dec 16 22:10:23 volumio-jan volumio[2746]: info: Loading plugin "lastfm"... Dec 16 22:10:23 volumio-jan volumio[2746]: info: Loading plugin "touch_display"... Dec 16 22:10:23 volumio-jan volumio[2746]: Starting albumart workers Dec 16 22:10:23 volumio-jan volumio[2746]: Starting albumart workers Dec 16 22:10:23 volumio-jan volumio[2746]: Starting albumart workers Dec 16 22:10:23 volumio-jan volumio[2746]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:23 volumio-jan volumio[2746]: info: Loading i18n strings for locale it Dec 16 22:10:23 volumio-jan volumio[2746]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:23 volumio-jan volumio[2746]: Updating browse sources language Dec 16 22:10:23 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: Express server listening on port 3000 Dec 16 22:10:24 volumio-jan volumio[2746]: [Metrics] WebUI: 5s 603.61ms Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: MPD Permissions set Dec 16 22:10:24 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919422 101 Dec 16 22:10:24 volumio-jan volumio[2746]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:24 volumio-jan volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:24 volumio-jan volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:24 volumio-jan volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:24 volumio-jan volumio[2746]: info: MPD running with PID2789 Dec 16 22:10:24 volumio-jan volumio[2746]: ,establishing connection Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: Reloading queue from file Dec 16 22:10:24 volumio-jan volumio[2746]: error: updateQueue error: null Dec 16 22:10:24 volumio-jan volumio[2746]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::pushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::pushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::setRandom false Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::pushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: Setting Device type: Raspberry PI Dec 16 22:10:24 volumio-jan volumio[2746]: error: updateQueue error: null Dec 16 22:10:24 volumio-jan volumio[2746]: info: Completed loading Core Plugins Dec 16 22:10:24 volumio-jan volumio[2746]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:24 volumio-jan volumio[2746]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreStateMachine::pushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:24 volumio-jan volumio[2746]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:24 volumio-jan volumio[2746]: info: Reading ALSA contributions from plugins. Dec 16 22:10:24 volumio-jan volumio[2746]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:24 volumio-jan volumio[2746]: info: Output device has changed, restarting MPD Dec 16 22:10:24 volumio-jan sudo[2893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:24 volumio-jan sudo[2893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:24 volumio-jan volumio[2746]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan sudo[2893]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan sudo[2896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:24 volumio-jan sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:24 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:24 volumio-jan volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: ___________ START PLUGINS ___________ Dec 16 22:10:24 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:24 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:24 volumio-jan volumio[2746]: info: Loading i18n strings for locale it Dec 16 22:10:24 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:24 volumio-jan volumio[2746]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:24 volumio-jan volumio[2746]: info: /tmp/myfifo created Dec 16 22:10:24 volumio-jan sudo[2905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:24 volumio-jan sudo[2905]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:24 volumio-jan sudo[2905]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:24 volumio-jan volumio[2746]: info: snd-dummy loaded Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:24 volumio-jan volumio[2746]: info: [1765919424778] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:24 volumio-jan volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:24 volumio-jan volumio[2746]: info: [1765919424874] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:24 volumio-jan volumio[2746]: info: [1765919424877] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:24 volumio-jan volumio[2746]: info: [now-playing-config] Config is up to date. Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:24 volumio-jan volumio[2746]: info: Discovery: Getting this device information Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:24 volumio-jan volumio[2746]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:24 volumio-jan volumio[2746]: info: [1765919424928] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:24 volumio-jan volumio[2746]: Cannot find translation for source LastFM Dec 16 22:10:24 volumio-jan volumio[2746]: info: [LastFM] scrobbler initiated! Dec 16 22:10:24 volumio-jan volumio[2746]: info: [LastFM] extended logging: false Dec 16 22:10:24 volumio-jan volumio[2746]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:24 volumio-jan volumio[2746]: info: [LastFM] Left init routine Dec 16 22:10:24 volumio-jan volumio[2746]: info: [LastFM] Socket already connected: true Dec 16 22:10:24 volumio-jan volumio[2746]: info: Loading i18n strings for locale it Dec 16 22:10:24 volumio-jan volumio[2746]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:24 volumio-jan volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:24 volumio-jan volumio[2746]: info: Volumio Calling Home Dec 16 22:10:24 volumio-jan volumio[2746]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:25 volumio-jan volumio[2746]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:25 volumio-jan sudo[2923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:25 volumio-jan sudo[2923]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:25 volumio-jan sudo[2774]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:25 volumio-jan sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:25 volumio-jan sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:25 volumio-jan systemd[1]: Reloading. Dec 16 22:10:25 volumio-jan sudo[2923]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:25 volumio-jan volumio[2746]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:25 volumio-jan volumio[2746]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:25 volumio-jan volumio[2746]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:25 volumio-jan volumio[2746]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:25 volumio-jan volumio[2746]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:25 volumio-jan volumio[2746]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:25 volumio-jan volumio[2746]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:25 volumio-jan volumio[2746]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:25 volumio-jan volumio[2746]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:25 volumio-jan volumio[2746]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:25 volumio-jan volumio[2746]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:25 volumio-jan volumio[2746]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:25 volumio-jan volumio[2746]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:25 volumio-jan volumio[2746]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:25 volumio-jan volumio[2746]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:25 volumio-jan volumio[2746]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:25 volumio-jan volumio[2746]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:25 volumio-jan volumio[2746]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:25 volumio-jan volumio[2746]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:25 volumio-jan volumio[2746]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:25 volumio-jan volumio[2746]: errno: -16, Dec 16 22:10:25 volumio-jan volumio[2746]: code: 'EBUSY', Dec 16 22:10:25 volumio-jan volumio[2746]: syscall: 'getaddrinfo', Dec 16 22:10:25 volumio-jan volumio[2746]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:25 volumio-jan volumio[2746]: } Dec 16 22:10:25 volumio-jan volumio[2746]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:25 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:25 volumio-jan mpd[2915]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:25 volumio-jan mpd[2915]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:25 volumio-jan mpd[2915]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:25 volumio-jan sudo[2958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:25 volumio-jan sudo[2958]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:25 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:25 volumio-jan sudo[2926]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:25 volumio-jan sudo[2958]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:25 volumio-jan sudo[2896]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:25 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:25 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:25 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:25 volumio-jan ntfs-3g[2819]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:25 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:25 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:25 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:25 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:26 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:26 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Dec 16 22:10:26 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:26 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:26 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:26 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:27 volumio-jan volumio[2971]: info: ------------------------------------------- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ----- Volumio3 ---- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ------------------------------------------- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ----- System startup ---- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ------------------------------------------- Dec 16 22:10:27 volumio-jan volumio[2971]: info: MYVOLUMIO Environment detected Dec 16 22:10:27 volumio-jan volumio[2971]: info: Plugin folders cleanup Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category audio_interface Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category miscellanea Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category music_service Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category plugins.json Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category system_controller Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category user_interface Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning into folder /data/plugins/ Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category miscellanea Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category system_controller Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category system_hardware Dec 16 22:10:27 volumio-jan volumio[2971]: info: Scanning category user_interface Dec 16 22:10:27 volumio-jan volumio[2971]: info: Plugin folders cleanup completed Dec 16 22:10:27 volumio-jan volumio[2971]: info: ------------------------------------------- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ----- Core plugins startup ---- Dec 16 22:10:27 volumio-jan volumio[2971]: info: ------------------------------------------- Dec 16 22:10:27 volumio-jan volumio[2971]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:27 volumio-jan volumio[2971]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:27 volumio-jan volumio[2971]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:27 volumio-jan volumio[2971]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:27 volumio-jan volumio[2971]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:27 volumio-jan volumio[2971]: info: Loading plugin "system"... Dec 16 22:10:27 volumio-jan volumio[2971]: info: Loading plugin "appearance"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "network"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Refreshing Cached IP Addresses Dec 16 22:10:28 volumio-jan sudo[2995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:28 volumio-jan sudo[2995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:28 volumio-jan sudo[2995]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:28 volumio-jan sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:28 volumio-jan sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:28 volumio-jan sudo[2997]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:28 volumio-jan sudo[3000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:28 volumio-jan sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:28 volumio-jan volumio[2971]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "services"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "alsa_controller"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "wizard"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "upnp"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: [1765919428686] Starting Upmpd Daemon Dec 16 22:10:28 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "my_music"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "mpd"... Dec 16 22:10:28 volumio-jan volumio[2971]: info: Creating MPD Configuration file Dec 16 22:10:28 volumio-jan sudo[3008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:28 volumio-jan sudo[3008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:28 volumio-jan volumio[2971]: info: Loading plugin "upnp_browser"... Dec 16 22:10:28 volumio-jan sudo[3008]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:28 volumio-jan sudo[3010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:28 volumio-jan sudo[3010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:28 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:28 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:28 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:28 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:29 volumio-jan volumio[2971]: info: Loading plugin "networkfs"... Dec 16 22:10:29 volumio-jan volumio[2971]: info: Starting Udev Watcher for removable devices Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:29 volumio-jan volumio[2971]: error: Cannot associate FS Label, not mounting Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:29 volumio-jan mpd[3015]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: boot Dec 16 22:10:29 volumio-jan volumio[2971]: info: Ignoring mount for partition: volumio Dec 16 22:10:29 volumio-jan volumio[2971]: info: Mounting Device PENDRIVE Dec 16 22:10:29 volumio-jan sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:29 volumio-jan sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:29 volumio-jan sudo[3033]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:29 volumio-jan volumio[2971]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:29 volumio-jan volumio[2971]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:29 volumio-jan volumio[2971]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:29 volumio-jan mpd[3015]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:29 volumio-jan mpd[3015]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:29 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:29 volumio-jan sudo[3010]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:29 volumio-jan volumio[2971]: info: Mounting Device MyBook Dec 16 22:10:29 volumio-jan sudo[3041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:29 volumio-jan sudo[3041]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:30 volumio-jan ntfs-3g[3044]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:30 volumio-jan ntfs-3g[3044]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:30 volumio-jan ntfs-3g[3044]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:30 volumio-jan ntfs-3g[3044]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:30 volumio-jan ntfs-3g[3044]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:30 volumio-jan sudo[3041]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:30 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "alarm-clock"... Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:30 volumio-jan volumio[2971]: info: Starting Shairport Sync Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "last_100"... Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "webradio"... Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:30 volumio-jan volumio[2971]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** For more information see Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:30 volumio-jan volumio[2971]: *** WARNING *** For more information see Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** For more information see Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:30 volumio-jan node[2971]: *** WARNING *** For more information see Dec 16 22:10:30 volumio-jan volumio[2971]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:30 volumio-jan volumio[2971]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:30 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:30 volumio-jan volumio[2971]: info: Plugin autostart is not enabled Dec 16 22:10:30 volumio-jan volumio[2971]: info: Loading plugin "now_playing"... Dec 16 22:10:30 volumio-jan sudo[3000]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:30 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:30] [connect] Successful connection Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "outputs"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "albumart"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Plugin example_plugin is not enabled Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "inputs"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "updater_comm"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Plugin mpdemulation is not enabled Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "rest_api"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "websocket"... Dec 16 22:10:31 volumio-jan volumio[2971]: info: Plugin gpio_control is not enabled Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:31 volumio-jan volumio[2971]: Forking 3 albumart workers Dec 16 22:10:31 volumio-jan volumio[2971]: info: Loading plugin "lastfm"... Dec 16 22:10:32 volumio-jan volumio[2971]: info: Loading plugin "touch_display"... Dec 16 22:10:32 volumio-jan volumio[2971]: Starting albumart workers Dec 16 22:10:32 volumio-jan volumio[2971]: Starting albumart workers Dec 16 22:10:32 volumio-jan volumio[2971]: Starting albumart workers Dec 16 22:10:32 volumio-jan volumio[2971]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:32 volumio-jan volumio[2971]: info: Loading i18n strings for locale it Dec 16 22:10:32 volumio-jan volumio[2971]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:32 volumio-jan volumio[2971]: Updating browse sources language Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: Express server listening on port 3000 Dec 16 22:10:32 volumio-jan volumio[2971]: [Metrics] WebUI: 5s 709.18ms Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: MPD Permissions set Dec 16 22:10:32 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919430 101 Dec 16 22:10:32 volumio-jan volumio[2971]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:32 volumio-jan volumio[2971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:32 volumio-jan volumio[2971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:32 volumio-jan volumio[2971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:32 volumio-jan volumio[2971]: info: MPD running with PID3015 Dec 16 22:10:32 volumio-jan volumio[2971]: ,establishing connection Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:32 volumio-jan volumio[2971]: info: Reloading queue from file Dec 16 22:10:32 volumio-jan volumio[2971]: error: updateQueue error: null Dec 16 22:10:32 volumio-jan volumio[2971]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::pushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:32 volumio-jan volumio[2971]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::pushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::setRandom false Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreStateMachine::pushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:32 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:32 volumio-jan volumio[2971]: info: Setting Device type: Raspberry PI Dec 16 22:10:32 volumio-jan volumio[2971]: error: updateQueue error: null Dec 16 22:10:33 volumio-jan volumio[2971]: info: Completed loading Core Plugins Dec 16 22:10:33 volumio-jan volumio[2971]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:33 volumio-jan volumio[2971]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreStateMachine::pushState Dec 16 22:10:33 volumio-jan volumio[2971]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:33 volumio-jan volumio[2971]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:33 volumio-jan volumio[2971]: info: Reading ALSA contributions from plugins. Dec 16 22:10:33 volumio-jan volumio[2971]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:33 volumio-jan volumio[2971]: info: Output device has changed, restarting MPD Dec 16 22:10:33 volumio-jan sudo[3119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:33 volumio-jan sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:33 volumio-jan volumio[2971]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:33 volumio-jan sudo[3119]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:33 volumio-jan sudo[3122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:33 volumio-jan sudo[3122]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:33 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:33 volumio-jan volumio[2971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:33 volumio-jan volumio[2971]: info: ___________ START PLUGINS ___________ Dec 16 22:10:33 volumio-jan volumio[2971]: info: Loading i18n strings for locale it Dec 16 22:10:33 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:33 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:33 volumio-jan volumio[2971]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:33 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:33 volumio-jan volumio[2971]: info: /tmp/myfifo created Dec 16 22:10:33 volumio-jan sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:33 volumio-jan sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:33 volumio-jan sudo[3131]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:33 volumio-jan volumio[2971]: info: snd-dummy loaded Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:33 volumio-jan volumio[2971]: info: [1765919433323] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:33 volumio-jan volumio[2971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:33 volumio-jan volumio[2971]: info: [1765919433424] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:33 volumio-jan volumio[2971]: info: [1765919433427] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:33 volumio-jan volumio[2971]: info: [now-playing-config] Config is up to date. Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:33 volumio-jan volumio[2971]: info: Discovery: Getting this device information Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:33 volumio-jan volumio[2971]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:33 volumio-jan volumio[2971]: info: [1765919433479] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:33 volumio-jan volumio[2971]: Cannot find translation for source LastFM Dec 16 22:10:33 volumio-jan volumio[2971]: info: [LastFM] scrobbler initiated! Dec 16 22:10:33 volumio-jan volumio[2971]: info: [LastFM] extended logging: false Dec 16 22:10:33 volumio-jan volumio[2971]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:33 volumio-jan volumio[2971]: info: [LastFM] Left init routine Dec 16 22:10:33 volumio-jan volumio[2971]: info: [LastFM] Socket already connected: true Dec 16 22:10:33 volumio-jan volumio[2971]: info: Loading i18n strings for locale it Dec 16 22:10:33 volumio-jan volumio[2971]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:33 volumio-jan volumio[2971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:33 volumio-jan volumio[2971]: info: Volumio Calling Home Dec 16 22:10:33 volumio-jan volumio[2971]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:33 volumio-jan volumio[2971]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:33 volumio-jan sudo[3149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:33 volumio-jan sudo[3149]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:33 volumio-jan sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:33 volumio-jan sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:33 volumio-jan sudo[3149]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:33 volumio-jan systemd[1]: Reloading. Dec 16 22:10:33 volumio-jan volumio[2971]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:33 volumio-jan volumio[2971]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:33 volumio-jan volumio[2971]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:33 volumio-jan volumio[2971]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:33 volumio-jan volumio[2971]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:33 volumio-jan volumio[2971]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:33 volumio-jan volumio[2971]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:33 volumio-jan volumio[2971]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:33 volumio-jan volumio[2971]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:33 volumio-jan volumio[2971]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:33 volumio-jan volumio[2971]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:33 volumio-jan volumio[2971]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:33 volumio-jan volumio[2971]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:33 volumio-jan volumio[2971]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:33 volumio-jan volumio[2971]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:33 volumio-jan volumio[2971]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:33 volumio-jan volumio[2971]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:33 volumio-jan volumio[2971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:33 volumio-jan volumio[2971]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:33 volumio-jan volumio[2971]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:33 volumio-jan volumio[2971]: errno: -16, Dec 16 22:10:33 volumio-jan volumio[2971]: code: 'EBUSY', Dec 16 22:10:33 volumio-jan volumio[2971]: syscall: 'getaddrinfo', Dec 16 22:10:33 volumio-jan volumio[2971]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:33 volumio-jan volumio[2971]: } Dec 16 22:10:33 volumio-jan volumio[2971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:33 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:34 volumio-jan mpd[3141]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:34 volumio-jan mpd[3141]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:34 volumio-jan mpd[3141]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:34 volumio-jan sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:34 volumio-jan sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:34 volumio-jan sudo[3184]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:34 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:34 volumio-jan sudo[3153]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:34 volumio-jan sudo[3122]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:34 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:34 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:34 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:34 volumio-jan ntfs-3g[3044]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:34 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:34 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:34 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:34 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:34 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:34 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Dec 16 22:10:34 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:34 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:34 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:34 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:35 volumio-jan volumio[3198]: info: ------------------------------------------- Dec 16 22:10:35 volumio-jan volumio[3198]: info: ----- Volumio3 ---- Dec 16 22:10:35 volumio-jan volumio[3198]: info: ------------------------------------------- Dec 16 22:10:35 volumio-jan volumio[3198]: info: ----- System startup ---- Dec 16 22:10:35 volumio-jan volumio[3198]: info: ------------------------------------------- Dec 16 22:10:36 volumio-jan volumio[3198]: info: MYVOLUMIO Environment detected Dec 16 22:10:36 volumio-jan volumio[3198]: info: Plugin folders cleanup Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category audio_interface Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category miscellanea Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category music_service Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category plugins.json Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category system_controller Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category user_interface Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning into folder /data/plugins/ Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category miscellanea Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category system_controller Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category system_hardware Dec 16 22:10:36 volumio-jan volumio[3198]: info: Scanning category user_interface Dec 16 22:10:36 volumio-jan volumio[3198]: info: Plugin folders cleanup completed Dec 16 22:10:36 volumio-jan volumio[3198]: info: ------------------------------------------- Dec 16 22:10:36 volumio-jan volumio[3198]: info: ----- Core plugins startup ---- Dec 16 22:10:36 volumio-jan volumio[3198]: info: ------------------------------------------- Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:36 volumio-jan volumio[3198]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:36 volumio-jan volumio[3198]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:36 volumio-jan volumio[3198]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugin "system"... Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugin "appearance"... Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugin "network"... Dec 16 22:10:36 volumio-jan volumio[3198]: info: Refreshing Cached IP Addresses Dec 16 22:10:36 volumio-jan sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:36 volumio-jan sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:36 volumio-jan sudo[3222]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:36 volumio-jan sudo[3224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:36 volumio-jan sudo[3224]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:36 volumio-jan sudo[3224]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:36 volumio-jan volumio[3198]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:36 volumio-jan sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:36 volumio-jan sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:37 volumio-jan volumio[3198]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "services"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "alsa_controller"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "wizard"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "upnp"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: [1765919437177] Starting Upmpd Daemon Dec 16 22:10:37 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "my_music"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "mpd"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Creating MPD Configuration file Dec 16 22:10:37 volumio-jan sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "upnp_browser"... Dec 16 22:10:37 volumio-jan sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:37 volumio-jan sudo[3235]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:37 volumio-jan sudo[3237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:37 volumio-jan sudo[3237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:37 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:37 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:37 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:37 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Loading plugin "networkfs"... Dec 16 22:10:37 volumio-jan volumio[3198]: info: Starting Udev Watcher for removable devices Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:38 volumio-jan volumio[3198]: error: Cannot associate FS Label, not mounting Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: boot Dec 16 22:10:38 volumio-jan volumio[3198]: info: Ignoring mount for partition: volumio Dec 16 22:10:38 volumio-jan mpd[3242]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:38 volumio-jan volumio[3198]: info: Mounting Device PENDRIVE Dec 16 22:10:38 volumio-jan sudo[3260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:38 volumio-jan sudo[3260]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:38 volumio-jan sudo[3260]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:38 volumio-jan volumio[3198]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:38 volumio-jan volumio[3198]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:38 volumio-jan volumio[3198]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:38 volumio-jan mpd[3242]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:38 volumio-jan mpd[3242]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:38 volumio-jan volumio[3198]: info: Mounting Device MyBook Dec 16 22:10:38 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:38 volumio-jan sudo[3237]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:38 volumio-jan sudo[3268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:38 volumio-jan sudo[3268]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:39 volumio-jan ntfs-3g[3271]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:39 volumio-jan ntfs-3g[3271]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:39 volumio-jan ntfs-3g[3271]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:39 volumio-jan ntfs-3g[3271]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:39 volumio-jan ntfs-3g[3271]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:39 volumio-jan sudo[3268]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:39 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "alarm-clock"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Starting Shairport Sync Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "last_100"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "webradio"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** For more information see Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** For more information see Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:39 volumio-jan volumio[3198]: *** WARNING *** For more information see Dec 16 22:10:39 volumio-jan node[3198]: *** WARNING *** For more information see Dec 16 22:10:39 volumio-jan volumio[3198]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:39 volumio-jan volumio[3198]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:39 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:39 volumio-jan volumio[3198]: info: Plugin autostart is not enabled Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "now_playing"... Dec 16 22:10:39 volumio-jan sudo[3228]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:39 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:39] [connect] Successful connection Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "outputs"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "albumart"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Plugin example_plugin is not enabled Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "inputs"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "updater_comm"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Plugin mpdemulation is not enabled Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "rest_api"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "websocket"... Dec 16 22:10:39 volumio-jan volumio[3198]: info: Plugin gpio_control is not enabled Dec 16 22:10:39 volumio-jan volumio[3198]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:40 volumio-jan volumio[3198]: Forking 3 albumart workers Dec 16 22:10:40 volumio-jan volumio[3198]: info: Loading plugin "lastfm"... Dec 16 22:10:40 volumio-jan volumio[3198]: info: Loading plugin "touch_display"... Dec 16 22:10:40 volumio-jan volumio[3198]: Starting albumart workers Dec 16 22:10:40 volumio-jan volumio[3198]: Starting albumart workers Dec 16 22:10:40 volumio-jan volumio[3198]: Starting albumart workers Dec 16 22:10:40 volumio-jan volumio[3198]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:40 volumio-jan volumio[3198]: info: Loading i18n strings for locale it Dec 16 22:10:40 volumio-jan volumio[3198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:40 volumio-jan volumio[3198]: Updating browse sources language Dec 16 22:10:40 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: Express server listening on port 3000 Dec 16 22:10:41 volumio-jan volumio[3198]: [Metrics] WebUI: 5s 715.32ms Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: MPD Permissions set Dec 16 22:10:41 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919439 101 Dec 16 22:10:41 volumio-jan volumio[3198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:41 volumio-jan volumio[3198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:41 volumio-jan volumio[3198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:41 volumio-jan volumio[3198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:41 volumio-jan volumio[3198]: info: MPD running with PID3242 Dec 16 22:10:41 volumio-jan volumio[3198]: ,establishing connection Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: Reloading queue from file Dec 16 22:10:41 volumio-jan volumio[3198]: error: updateQueue error: null Dec 16 22:10:41 volumio-jan volumio[3198]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::pushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::pushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::setRandom false Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::pushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: Setting Device type: Raspberry PI Dec 16 22:10:41 volumio-jan volumio[3198]: error: updateQueue error: null Dec 16 22:10:41 volumio-jan volumio[3198]: info: Completed loading Core Plugins Dec 16 22:10:41 volumio-jan volumio[3198]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:41 volumio-jan volumio[3198]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreStateMachine::pushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:41 volumio-jan volumio[3198]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:41 volumio-jan volumio[3198]: info: Reading ALSA contributions from plugins. Dec 16 22:10:41 volumio-jan volumio[3198]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:41 volumio-jan volumio[3198]: info: Output device has changed, restarting MPD Dec 16 22:10:41 volumio-jan sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:41 volumio-jan sudo[3346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:41 volumio-jan volumio[3198]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan sudo[3346]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan sudo[3349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:41 volumio-jan sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:41 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:41 volumio-jan volumio[3198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: ___________ START PLUGINS ___________ Dec 16 22:10:41 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:41 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:41 volumio-jan volumio[3198]: info: Loading i18n strings for locale it Dec 16 22:10:41 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:41 volumio-jan volumio[3198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:41 volumio-jan volumio[3198]: info: /tmp/myfifo created Dec 16 22:10:41 volumio-jan sudo[3358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:41 volumio-jan sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:41 volumio-jan sudo[3358]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:41 volumio-jan volumio[3198]: info: snd-dummy loaded Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:41 volumio-jan volumio[3198]: info: [1765919441812] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:41 volumio-jan volumio[3198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:41 volumio-jan volumio[3198]: info: [1765919441907] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:41 volumio-jan volumio[3198]: info: [1765919441909] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:41 volumio-jan volumio[3198]: info: [now-playing-config] Config is up to date. Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:41 volumio-jan volumio[3198]: info: Discovery: Getting this device information Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:41 volumio-jan volumio[3198]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:41 volumio-jan volumio[3198]: info: [1765919441954] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:41 volumio-jan volumio[3198]: Cannot find translation for source LastFM Dec 16 22:10:41 volumio-jan volumio[3198]: info: [LastFM] scrobbler initiated! Dec 16 22:10:41 volumio-jan volumio[3198]: info: [LastFM] extended logging: false Dec 16 22:10:41 volumio-jan volumio[3198]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:41 volumio-jan volumio[3198]: info: [LastFM] Left init routine Dec 16 22:10:41 volumio-jan volumio[3198]: info: [LastFM] Socket already connected: true Dec 16 22:10:41 volumio-jan volumio[3198]: info: Loading i18n strings for locale it Dec 16 22:10:41 volumio-jan volumio[3198]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:41 volumio-jan volumio[3198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:41 volumio-jan volumio[3198]: info: Volumio Calling Home Dec 16 22:10:41 volumio-jan volumio[3198]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:42 volumio-jan volumio[3198]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:42 volumio-jan sudo[3376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:42 volumio-jan sudo[3376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:42 volumio-jan sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:42 volumio-jan sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:42 volumio-jan systemd[1]: Reloading. Dec 16 22:10:42 volumio-jan volumio[3198]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:42 volumio-jan volumio[3198]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:42 volumio-jan volumio[3198]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:42 volumio-jan volumio[3198]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:42 volumio-jan volumio[3198]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:42 volumio-jan volumio[3198]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:42 volumio-jan volumio[3198]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:42 volumio-jan volumio[3198]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:42 volumio-jan volumio[3198]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:42 volumio-jan volumio[3198]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:42 volumio-jan volumio[3198]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:42 volumio-jan volumio[3198]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:42 volumio-jan volumio[3198]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:42 volumio-jan volumio[3198]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:42 volumio-jan volumio[3198]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:42 volumio-jan volumio[3198]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:42 volumio-jan volumio[3198]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:42 volumio-jan volumio[3198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:42 volumio-jan sudo[3376]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:42 volumio-jan volumio[3198]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:42 volumio-jan volumio[3198]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:42 volumio-jan volumio[3198]: errno: -16, Dec 16 22:10:42 volumio-jan volumio[3198]: code: 'EBUSY', Dec 16 22:10:42 volumio-jan volumio[3198]: syscall: 'getaddrinfo', Dec 16 22:10:42 volumio-jan volumio[3198]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:42 volumio-jan volumio[3198]: } Dec 16 22:10:42 volumio-jan volumio[3198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:42 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:42 volumio-jan mpd[3368]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:42 volumio-jan mpd[3368]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:42 volumio-jan mpd[3368]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:42 volumio-jan sudo[3409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:42 volumio-jan sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:42 volumio-jan sudo[3409]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:42 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:42 volumio-jan sudo[3349]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:42 volumio-jan sudo[3379]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:42 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:42 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:42 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:42 volumio-jan ntfs-3g[3271]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:42 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:42 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:42 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:42 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:43 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:43 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Dec 16 22:10:43 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:43 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:43 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:43 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:44 volumio-jan volumio[3425]: info: ------------------------------------------- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ----- Volumio3 ---- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ------------------------------------------- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ----- System startup ---- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ------------------------------------------- Dec 16 22:10:44 volumio-jan volumio[3425]: info: MYVOLUMIO Environment detected Dec 16 22:10:44 volumio-jan volumio[3425]: info: Plugin folders cleanup Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category audio_interface Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category miscellanea Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category music_service Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category plugins.json Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category system_controller Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category user_interface Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning into folder /data/plugins/ Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category miscellanea Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category system_controller Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category system_hardware Dec 16 22:10:44 volumio-jan volumio[3425]: info: Scanning category user_interface Dec 16 22:10:44 volumio-jan volumio[3425]: info: Plugin folders cleanup completed Dec 16 22:10:44 volumio-jan volumio[3425]: info: ------------------------------------------- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ----- Core plugins startup ---- Dec 16 22:10:44 volumio-jan volumio[3425]: info: ------------------------------------------- Dec 16 22:10:44 volumio-jan volumio[3425]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:44 volumio-jan volumio[3425]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:44 volumio-jan volumio[3425]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:44 volumio-jan volumio[3425]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:44 volumio-jan volumio[3425]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:44 volumio-jan volumio[3425]: info: Loading plugin "system"... Dec 16 22:10:44 volumio-jan volumio[3425]: info: Loading plugin "appearance"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "network"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Refreshing Cached IP Addresses Dec 16 22:10:45 volumio-jan sudo[3448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:45 volumio-jan sudo[3448]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:45 volumio-jan sudo[3450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:45 volumio-jan sudo[3450]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:45 volumio-jan sudo[3448]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:45 volumio-jan sudo[3450]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:45 volumio-jan sudo[3454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:45 volumio-jan sudo[3454]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:45 volumio-jan volumio[3425]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "services"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "alsa_controller"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "wizard"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "upnp"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: [1765919445622] Starting Upmpd Daemon Dec 16 22:10:45 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "my_music"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "mpd"... Dec 16 22:10:45 volumio-jan volumio[3425]: info: Creating MPD Configuration file Dec 16 22:10:45 volumio-jan sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:45 volumio-jan volumio[3425]: info: Loading plugin "upnp_browser"... Dec 16 22:10:45 volumio-jan sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:45 volumio-jan sudo[3461]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:45 volumio-jan sudo[3463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:45 volumio-jan sudo[3463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:45 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:45 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:45 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:45 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:46 volumio-jan volumio[3425]: info: Loading plugin "networkfs"... Dec 16 22:10:46 volumio-jan volumio[3425]: info: Starting Udev Watcher for removable devices Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:46 volumio-jan volumio[3425]: error: Cannot associate FS Label, not mounting Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: boot Dec 16 22:10:46 volumio-jan volumio[3425]: info: Ignoring mount for partition: volumio Dec 16 22:10:46 volumio-jan mpd[3468]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:46 volumio-jan volumio[3425]: info: Mounting Device PENDRIVE Dec 16 22:10:46 volumio-jan sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:46 volumio-jan sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:46 volumio-jan sudo[3486]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:46 volumio-jan volumio[3425]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:46 volumio-jan volumio[3425]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:46 volumio-jan volumio[3425]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:46 volumio-jan mpd[3468]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:46 volumio-jan mpd[3468]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:46 volumio-jan volumio[3425]: info: Mounting Device MyBook Dec 16 22:10:46 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:46 volumio-jan sudo[3463]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:46 volumio-jan sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:46 volumio-jan sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:47 volumio-jan ntfs-3g[3497]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:47 volumio-jan ntfs-3g[3497]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:47 volumio-jan ntfs-3g[3497]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:47 volumio-jan ntfs-3g[3497]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:47 volumio-jan ntfs-3g[3497]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:47 volumio-jan sudo[3494]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:47 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "alarm-clock"... Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:47 volumio-jan volumio[3425]: info: Starting Shairport Sync Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "last_100"... Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "webradio"... Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:47 volumio-jan volumio[3425]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** For more information see Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:47 volumio-jan volumio[3425]: *** WARNING *** For more information see Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** For more information see Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:47 volumio-jan node[3425]: *** WARNING *** For more information see Dec 16 22:10:47 volumio-jan volumio[3425]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:47 volumio-jan volumio[3425]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:47 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:47 volumio-jan volumio[3425]: info: Plugin autostart is not enabled Dec 16 22:10:47 volumio-jan volumio[3425]: info: Loading plugin "now_playing"... Dec 16 22:10:47 volumio-jan sudo[3454]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:47 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:47] [connect] Successful connection Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "outputs"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "albumart"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Plugin example_plugin is not enabled Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "inputs"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "updater_comm"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Plugin mpdemulation is not enabled Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "rest_api"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "websocket"... Dec 16 22:10:48 volumio-jan volumio[3425]: info: Plugin gpio_control is not enabled Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:48 volumio-jan volumio[3425]: Forking 3 albumart workers Dec 16 22:10:48 volumio-jan volumio[3425]: info: Loading plugin "lastfm"... Dec 16 22:10:49 volumio-jan volumio[3425]: info: Loading plugin "touch_display"... Dec 16 22:10:49 volumio-jan volumio[3425]: Starting albumart workers Dec 16 22:10:49 volumio-jan volumio[3425]: Starting albumart workers Dec 16 22:10:49 volumio-jan volumio[3425]: Starting albumart workers Dec 16 22:10:49 volumio-jan volumio[3425]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:49 volumio-jan volumio[3425]: info: Loading i18n strings for locale it Dec 16 22:10:49 volumio-jan volumio[3425]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:49 volumio-jan volumio[3425]: Updating browse sources language Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: Express server listening on port 3000 Dec 16 22:10:49 volumio-jan volumio[3425]: [Metrics] WebUI: 5s 591.13ms Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: MPD Permissions set Dec 16 22:10:49 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919447 101 Dec 16 22:10:49 volumio-jan volumio[3425]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:49 volumio-jan volumio[3425]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:49 volumio-jan volumio[3425]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:49 volumio-jan volumio[3425]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:49 volumio-jan volumio[3425]: info: MPD running with PID3468 Dec 16 22:10:49 volumio-jan volumio[3425]: ,establishing connection Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:49 volumio-jan volumio[3425]: info: Reloading queue from file Dec 16 22:10:49 volumio-jan volumio[3425]: error: updateQueue error: null Dec 16 22:10:49 volumio-jan volumio[3425]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::pushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:49 volumio-jan volumio[3425]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::pushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::setRandom false Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::pushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: Setting Device type: Raspberry PI Dec 16 22:10:49 volumio-jan volumio[3425]: error: updateQueue error: null Dec 16 22:10:49 volumio-jan volumio[3425]: info: Completed loading Core Plugins Dec 16 22:10:49 volumio-jan volumio[3425]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:49 volumio-jan volumio[3425]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreStateMachine::pushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:49 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:49 volumio-jan volumio[3425]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:49 volumio-jan volumio[3425]: info: Reading ALSA contributions from plugins. Dec 16 22:10:50 volumio-jan volumio[3425]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:50 volumio-jan volumio[3425]: info: Output device has changed, restarting MPD Dec 16 22:10:50 volumio-jan sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:50 volumio-jan sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:50 volumio-jan volumio[3425]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:50 volumio-jan sudo[3572]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:50 volumio-jan sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:50 volumio-jan sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:50 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:50 volumio-jan volumio[3425]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:50 volumio-jan volumio[3425]: info: ___________ START PLUGINS ___________ Dec 16 22:10:50 volumio-jan volumio[3425]: info: Loading i18n strings for locale it Dec 16 22:10:50 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:50 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:50 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:50 volumio-jan volumio[3425]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:50 volumio-jan volumio[3425]: info: /tmp/myfifo created Dec 16 22:10:50 volumio-jan sudo[3584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:50 volumio-jan sudo[3584]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:50 volumio-jan sudo[3584]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:50 volumio-jan volumio[3425]: info: snd-dummy loaded Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:50 volumio-jan volumio[3425]: info: [1765919450209] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:50 volumio-jan ntpd[660]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 16 22:10:50 volumio-jan ntpd[660]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 16 22:10:50 volumio-jan volumio[3425]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:50 volumio-jan volumio[3425]: info: [1765919450306] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:50 volumio-jan volumio[3425]: info: [1765919450309] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:50 volumio-jan volumio[3425]: info: [now-playing-config] Config is up to date. Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:50 volumio-jan volumio[3425]: info: Discovery: Getting this device information Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:50 volumio-jan volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:50 volumio-jan volumio[3425]: info: [1765919450358] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:50 volumio-jan volumio[3425]: Cannot find translation for source LastFM Dec 16 22:10:50 volumio-jan volumio[3425]: info: [LastFM] scrobbler initiated! Dec 16 22:10:50 volumio-jan volumio[3425]: info: [LastFM] extended logging: false Dec 16 22:10:50 volumio-jan volumio[3425]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:50 volumio-jan volumio[3425]: info: [LastFM] Left init routine Dec 16 22:10:50 volumio-jan volumio[3425]: info: [LastFM] Socket already connected: true Dec 16 22:10:50 volumio-jan volumio[3425]: info: Loading i18n strings for locale it Dec 16 22:10:50 volumio-jan volumio[3425]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:50 volumio-jan volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:50 volumio-jan volumio[3425]: info: Volumio Calling Home Dec 16 22:10:50 volumio-jan volumio[3425]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:50 volumio-jan volumio[3425]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:50 volumio-jan sudo[3602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:50 volumio-jan sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:50 volumio-jan sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:50 volumio-jan sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:50 volumio-jan sudo[3602]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:50 volumio-jan systemd[1]: Reloading. Dec 16 22:10:50 volumio-jan volumio[3425]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:50 volumio-jan volumio[3425]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:50 volumio-jan volumio[3425]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:50 volumio-jan volumio[3425]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:50 volumio-jan volumio[3425]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:50 volumio-jan volumio[3425]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:50 volumio-jan volumio[3425]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:50 volumio-jan volumio[3425]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:50 volumio-jan volumio[3425]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:50 volumio-jan volumio[3425]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:50 volumio-jan volumio[3425]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:50 volumio-jan volumio[3425]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:50 volumio-jan volumio[3425]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:50 volumio-jan volumio[3425]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:50 volumio-jan volumio[3425]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:50 volumio-jan volumio[3425]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:50 volumio-jan volumio[3425]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:50 volumio-jan volumio[3425]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:50 volumio-jan volumio[3425]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:50 volumio-jan volumio[3425]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:50 volumio-jan volumio[3425]: errno: -16, Dec 16 22:10:50 volumio-jan volumio[3425]: code: 'EBUSY', Dec 16 22:10:50 volumio-jan volumio[3425]: syscall: 'getaddrinfo', Dec 16 22:10:50 volumio-jan volumio[3425]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:50 volumio-jan volumio[3425]: } Dec 16 22:10:50 volumio-jan volumio[3425]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:50 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:50 volumio-jan mpd[3594]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:51 volumio-jan mpd[3594]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:51 volumio-jan mpd[3594]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:51 volumio-jan sudo[3637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:51 volumio-jan sudo[3637]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:51 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:51 volumio-jan sudo[3606]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:51 volumio-jan sudo[3575]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:51 volumio-jan sudo[3637]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:51 volumio-jan ntpd[660]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 16 22:10:51 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:51 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:51 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:51 volumio-jan ntfs-3g[3497]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:51 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:51 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:51 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:51 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:51 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:10:51 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Dec 16 22:10:51 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:51 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:10:51 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:10:51 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:10:52 volumio-jan volumio[3652]: info: ------------------------------------------- Dec 16 22:10:52 volumio-jan volumio[3652]: info: ----- Volumio3 ---- Dec 16 22:10:52 volumio-jan volumio[3652]: info: ------------------------------------------- Dec 16 22:10:52 volumio-jan volumio[3652]: info: ----- System startup ---- Dec 16 22:10:52 volumio-jan volumio[3652]: info: ------------------------------------------- Dec 16 22:10:52 volumio-jan volumio[3652]: info: MYVOLUMIO Environment detected Dec 16 22:10:53 volumio-jan volumio[3652]: info: Plugin folders cleanup Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category audio_interface Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category miscellanea Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category music_service Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category plugins.json Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category system_controller Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category user_interface Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning into folder /data/plugins/ Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category miscellanea Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category system_controller Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category system_hardware Dec 16 22:10:53 volumio-jan volumio[3652]: info: Scanning category user_interface Dec 16 22:10:53 volumio-jan volumio[3652]: info: Plugin folders cleanup completed Dec 16 22:10:53 volumio-jan volumio[3652]: info: ------------------------------------------- Dec 16 22:10:53 volumio-jan volumio[3652]: info: ----- Core plugins startup ---- Dec 16 22:10:53 volumio-jan volumio[3652]: info: ------------------------------------------- Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:10:53 volumio-jan volumio[3652]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:10:53 volumio-jan volumio[3652]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:10:53 volumio-jan volumio[3652]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugins from folder /data/plugins/ Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "system"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "appearance"... Dec 16 22:10:53 volumio-jan ntpd[660]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "network"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Refreshing Cached IP Addresses Dec 16 22:10:53 volumio-jan sudo[3676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:10:53 volumio-jan sudo[3676]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:53 volumio-jan sudo[3676]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "peppy_screensaver"... Dec 16 22:10:53 volumio-jan sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:10:53 volumio-jan sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:53 volumio-jan sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:10:53 volumio-jan sudo[3679]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:53 volumio-jan sudo[3681]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:53 volumio-jan volumio[3652]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "services"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "alsa_controller"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "wizard"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "upnp"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: [1765919453991] Starting Upmpd Daemon Dec 16 22:10:53 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "my_music"... Dec 16 22:10:53 volumio-jan volumio[3652]: info: Loading plugin "mpd"... Dec 16 22:10:54 volumio-jan volumio[3652]: info: Creating MPD Configuration file Dec 16 22:10:54 volumio-jan sudo[3689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:54 volumio-jan volumio[3652]: info: Loading plugin "upnp_browser"... Dec 16 22:10:54 volumio-jan sudo[3689]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:54 volumio-jan sudo[3689]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:54 volumio-jan sudo[3691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:54 volumio-jan sudo[3691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:54 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:54 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:54 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:54 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:54 volumio-jan volumio[3652]: info: Loading plugin "networkfs"... Dec 16 22:10:54 volumio-jan volumio[3652]: info: Starting Udev Watcher for removable devices Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: RECOVERY Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: volumio_data Dec 16 22:10:54 volumio-jan volumio[3652]: error: Cannot associate FS Label, not mounting Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: SETTINGS Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:10:54 volumio-jan mpd[3696]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: boot Dec 16 22:10:54 volumio-jan volumio[3652]: info: Ignoring mount for partition: volumio Dec 16 22:10:55 volumio-jan volumio[3652]: info: Mounting Device PENDRIVE Dec 16 22:10:55 volumio-jan sudo[3714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:55 volumio-jan sudo[3714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:55 volumio-jan mpd[3696]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:55 volumio-jan mpd[3696]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:55 volumio-jan sudo[3714]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:55 volumio-jan volumio[3652]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:55 volumio-jan volumio[3652]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:55 volumio-jan volumio[3652]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:10:55 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:55 volumio-jan sudo[3691]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:55 volumio-jan volumio[3652]: info: Mounting Device MyBook Dec 16 22:10:55 volumio-jan sudo[3722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:55 volumio-jan sudo[3722]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:55 volumio-jan ntfs-3g[3725]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:10:55 volumio-jan ntfs-3g[3725]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:10:55 volumio-jan ntfs-3g[3725]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:10:55 volumio-jan ntfs-3g[3725]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:10:55 volumio-jan ntfs-3g[3725]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:10:55 volumio-jan sudo[3722]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:55 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:55 volumio-jan volumio[3652]: info: Loading plugin "alarm-clock"... Dec 16 22:10:55 volumio-jan volumio[3652]: info: Loading plugin "airplay_emulation"... Dec 16 22:10:55 volumio-jan volumio[3652]: info: Starting Shairport Sync Dec 16 22:10:55 volumio-jan volumio[3652]: info: Loading plugin "last_100"... Dec 16 22:10:55 volumio-jan volumio[3652]: info: Loading plugin "webradio"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "i2s_dacs"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: I2S DAC not set, start Auto-detection Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "volumiodiscovery"... Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** For more information see Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:56 volumio-jan volumio[3652]: *** WARNING *** For more information see Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** For more information see Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:10:56 volumio-jan node[3652]: *** WARNING *** For more information see Dec 16 22:10:56 volumio-jan volumio[3652]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:10:56 volumio-jan volumio[3652]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:10:56 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:10:56 volumio-jan volumio[3652]: info: Plugin autostart is not enabled Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "now_playing"... Dec 16 22:10:56 volumio-jan sudo[3681]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:56 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:56] [connect] Successful connection Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "outputs"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "albumart"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Plugin example_plugin is not enabled Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "inputs"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "updater_comm"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Plugin mpdemulation is not enabled Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "rest_api"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "websocket"... Dec 16 22:10:56 volumio-jan volumio[3652]: info: Plugin gpio_control is not enabled Dec 16 22:10:56 volumio-jan volumio[3652]: info: Loading plugin "rotaryencoder2"... Dec 16 22:10:56 volumio-jan volumio[3652]: Forking 3 albumart workers Dec 16 22:10:56 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Vodafone-JAN-NET" Dec 16 22:10:56 volumio-jan wpa_supplicant[771]: wlan0: Trying to associate with SSID 'Vodafone-JAN-NET' Dec 16 22:10:57 volumio-jan volumio[3652]: info: Loading plugin "lastfm"... Dec 16 22:10:57 volumio-jan volumio[3652]: info: Loading plugin "touch_display"... Dec 16 22:10:57 volumio-jan volumio[3652]: Starting albumart workers Dec 16 22:10:57 volumio-jan volumio[3652]: Starting albumart workers Dec 16 22:10:57 volumio-jan volumio[3652]: Starting albumart workers Dec 16 22:10:57 volumio-jan volumio[3652]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:10:57 volumio-jan volumio[3652]: info: Loading i18n strings for locale it Dec 16 22:10:57 volumio-jan volumio[3652]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:57 volumio-jan volumio[3652]: Updating browse sources language Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::initPlayerControls Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:57 volumio-jan volumio[3652]: Express server listening on port 3000 Dec 16 22:10:57 volumio-jan volumio[3652]: [Metrics] WebUI: 5s 791.25ms Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::resetVolumioState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::getcurrentVolume Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: MPD Permissions set Dec 16 22:10:58 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919456 101 Dec 16 22:10:58 volumio-jan volumio[3652]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:10:58 volumio-jan volumio[3652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:10:58 volumio-jan volumio[3652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:10:58 volumio-jan volumio[3652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:10:58 volumio-jan volumio[3652]: info: MPD running with PID3696 Dec 16 22:10:58 volumio-jan volumio[3652]: ,establishing connection Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: Reloading queue from file Dec 16 22:10:58 volumio-jan volumio[3652]: error: updateQueue error: null Dec 16 22:10:58 volumio-jan volumio[3652]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::pushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::updateTrackBlock Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrackBlock Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::pushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::setRandom false Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::pushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: Setting Device type: Raspberry PI Dec 16 22:10:58 volumio-jan volumio[3652]: error: updateQueue error: null Dec 16 22:10:58 volumio-jan volumio[3652]: info: Completed loading Core Plugins Dec 16 22:10:58 volumio-jan volumio[3652]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:58 volumio-jan volumio[3652]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreStateMachine::pushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioPushState Dec 16 22:10:58 volumio-jan volumio[3652]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:10:58 volumio-jan volumio[3652]: info: Reading ALSA contributions from plugins. Dec 16 22:10:58 volumio-jan volumio[3652]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:10:58 volumio-jan volumio[3652]: info: Output device has changed, restarting MPD Dec 16 22:10:58 volumio-jan sudo[3800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:10:58 volumio-jan sudo[3800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:58 volumio-jan volumio[3652]: info: Output device has changed, restarting Shairport Sync Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan sudo[3800]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan sudo[3803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:10:58 volumio-jan sudo[3803]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:58 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:10:58 volumio-jan volumio[3652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: ___________ START PLUGINS ___________ Dec 16 22:10:58 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:10:58 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:10:58 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:10:58 volumio-jan volumio[3652]: info: Loading i18n strings for locale it Dec 16 22:10:58 volumio-jan volumio[3652]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:58 volumio-jan volumio[3652]: info: /tmp/myfifo created Dec 16 22:10:58 volumio-jan sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:10:58 volumio-jan sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:58 volumio-jan sudo[3812]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:58 volumio-jan volumio[3652]: info: snd-dummy loaded Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:58 volumio-jan volumio[3652]: info: [1765919458683] CoreMusicLibrary::Adding element Media Servers Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:10:58 volumio-jan volumio[3652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:58 volumio-jan volumio[3652]: info: [1765919458778] CoreMusicLibrary::Adding element Last_100 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:58 volumio-jan volumio[3652]: info: [1765919458781] CoreMusicLibrary::Adding element Webradio Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:58 volumio-jan volumio[3652]: info: [now-playing-config] Config is up to date. Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:10:58 volumio-jan volumio[3652]: info: Discovery: Getting this device information Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioGetState Dec 16 22:10:58 volumio-jan volumio[3652]: info: CorePlayQueue::getTrack 0 Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:10:58 volumio-jan volumio[3652]: info: [1765919458827] CoreMusicLibrary::Adding element LastFM Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:10:58 volumio-jan volumio[3652]: Cannot find translation for source LastFM Dec 16 22:10:58 volumio-jan volumio[3652]: info: [LastFM] scrobbler initiated! Dec 16 22:10:58 volumio-jan volumio[3652]: info: [LastFM] extended logging: false Dec 16 22:10:58 volumio-jan volumio[3652]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:10:58 volumio-jan volumio[3652]: info: [LastFM] Left init routine Dec 16 22:10:58 volumio-jan volumio[3652]: info: [LastFM] Socket already connected: true Dec 16 22:10:58 volumio-jan volumio[3652]: info: Loading i18n strings for locale it Dec 16 22:10:58 volumio-jan volumio[3652]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:10:58 volumio-jan volumio[3652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:10:58 volumio-jan volumio[3652]: info: Volumio Calling Home Dec 16 22:10:58 volumio-jan volumio[3652]: info: Preparing to generate the ALSA configuration file Dec 16 22:10:58 volumio-jan volumio[3652]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:10:58 volumio-jan sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:10:58 volumio-jan sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:58 volumio-jan sudo[3833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:10:58 volumio-jan sudo[3833]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:58 volumio-jan systemd[1]: Reloading. Dec 16 22:10:59 volumio-jan sudo[3830]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:59 volumio-jan volumio[3652]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:10:59 volumio-jan volumio[3652]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:10:59 volumio-jan volumio[3652]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:10:59 volumio-jan volumio[3652]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:10:59 volumio-jan volumio[3652]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:10:59 volumio-jan volumio[3652]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:10:59 volumio-jan volumio[3652]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:10:59 volumio-jan volumio[3652]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:10:59 volumio-jan volumio[3652]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:10:59 volumio-jan volumio[3652]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:10:59 volumio-jan volumio[3652]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:10:59 volumio-jan volumio[3652]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:10:59 volumio-jan volumio[3652]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:10:59 volumio-jan volumio[3652]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:10:59 volumio-jan volumio[3652]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:10:59 volumio-jan volumio[3652]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:10:59 volumio-jan volumio[3652]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:10:59 volumio-jan volumio[3652]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:59 volumio-jan volumio[3652]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:10:59 volumio-jan volumio[3652]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:10:59 volumio-jan volumio[3652]: errno: -16, Dec 16 22:10:59 volumio-jan volumio[3652]: code: 'EBUSY', Dec 16 22:10:59 volumio-jan volumio[3652]: syscall: 'getaddrinfo', Dec 16 22:10:59 volumio-jan volumio[3652]: hostname: 'ws.audioscrobbler.com' Dec 16 22:10:59 volumio-jan volumio[3652]: } Dec 16 22:10:59 volumio-jan volumio[3652]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:10:59 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:10:59 volumio-jan mpd[3822]: Dec 16 22:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:10:59 volumio-jan mpd[3822]: Dec 16 22:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:10:59 volumio-jan mpd[3822]: Dec 16 22:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:10:59 volumio-jan sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:09 Dec 16 22:10:59 volumio-jan sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: carrier acquired Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: connected to Access Point `Vodafone-JAN-NET' Dec 16 22:10:59 volumio-jan wpa_supplicant[771]: wlan0: Associated with 90:35:6e:c5:7b:ac Dec 16 22:10:59 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:35:6e:c5:7b:ac completed [id=0 id_str=] Dec 16 22:10:59 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 22:10:59 volumio-jan dhcpcd[783]: DUID 00:01:00:01:29:81:36:21:d8:3a:dd:3a:0c:da Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: IAID dd:3a:0c:db Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: adding address fe80::5d06:44f2:904a:c1f8 Dec 16 22:10:59 volumio-jan dhcpcd[783]: ipv6_addaddr1: Permission denied Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: carrier lost Dec 16 22:10:59 volumio-jan wpa_supplicant[771]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: carrier acquired Dec 16 22:10:59 volumio-jan dhcpcd[783]: wlan0: IAID dd:3a:0c:db Dec 16 22:10:59 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:10:59 volumio-jan sudo[3833]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:59 volumio-jan sudo[3803]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:59 volumio-jan sudo[3865]: pam_unix(sudo:session): session closed for user root Dec 16 22:10:59 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 22:10:59 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:10:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 22:10:59 volumio-jan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 22:10:59 volumio-jan ntfs-3g[3725]: Unmounting /dev/sda2 (MyBook) Dec 16 22:10:59 volumio-jan systemd[1]: media-MyBook.mount: Succeeded. Dec 16 22:10:59 volumio-jan systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 22:10:59 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:10:59 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:11:00 volumio-jan systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 22:11:00 volumio-jan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Dec 16 22:11:00 volumio-jan systemd[1]: Started dynamicswap service. Dec 16 22:11:00 volumio-jan systemd[1]: Stopped Volumio Backend Module. Dec 16 22:11:00 volumio-jan systemd[1]: Started Volumio Backend Module. Dec 16 22:11:00 volumio-jan systemd[1]: dynamicswap.service: Succeeded. Dec 16 22:11:00 volumio-jan dhcpcd[783]: wlan0: soliciting an IPv6 router Dec 16 22:11:00 volumio-jan dhcpcd[783]: wlan0: soliciting a DHCP lease Dec 16 22:11:01 volumio-jan volumio[3893]: info: ------------------------------------------- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ----- Volumio3 ---- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ------------------------------------------- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ----- System startup ---- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ------------------------------------------- Dec 16 22:11:01 volumio-jan volumio[3893]: info: MYVOLUMIO Environment detected Dec 16 22:11:01 volumio-jan volumio[3893]: info: Plugin folders cleanup Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning into folder /volumio/app/plugins/ Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category audio_interface Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category miscellanea Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category music_service Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category plugins.json Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category system_controller Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category user_interface Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning into folder /data/plugins/ Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category miscellanea Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category system_controller Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category system_hardware Dec 16 22:11:01 volumio-jan volumio[3893]: info: Scanning category user_interface Dec 16 22:11:01 volumio-jan volumio[3893]: info: Plugin folders cleanup completed Dec 16 22:11:01 volumio-jan volumio[3893]: info: ------------------------------------------- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ----- Core plugins startup ---- Dec 16 22:11:01 volumio-jan volumio[3893]: info: ------------------------------------------- Dec 16 22:11:01 volumio-jan volumio[3893]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 22:11:01 volumio-jan volumio[3893]: info: Adding plugin upnp to MyMusic Plugins Dec 16 22:11:01 volumio-jan volumio[3893]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 22:11:01 volumio-jan volumio[3893]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 22:11:01 volumio-jan volumio[3893]: info: Loading plugins from folder /data/plugins/ Dec 16 22:11:01 volumio-jan volumio[3893]: info: Loading plugin "system"... Dec 16 22:11:01 volumio-jan volumio[3893]: info: Loading plugin "appearance"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "network"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Refreshing Cached IP Addresses Dec 16 22:11:02 volumio-jan sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:11:02 volumio-jan sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:02 volumio-jan sudo[3916]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:02 volumio-jan sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:11:02 volumio-jan sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:02 volumio-jan sudo[3918]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "peppy_screensaver"... Dec 16 22:11:02 volumio-jan sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 22:11:02 volumio-jan sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:02 volumio-jan volumio[3893]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "services"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "alsa_controller"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "wizard"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "volumio_command_line_client"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "upnp"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: [1765919462636] Starting Upmpd Daemon Dec 16 22:11:02 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "my_music"... Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "mpd"... Dec 16 22:11:02 volumio-jan dhcpcd[783]: wlan0: offered 192.168.0.7 from 192.168.0.254 Dec 16 22:11:02 volumio-jan volumio[3893]: info: Creating MPD Configuration file Dec 16 22:11:02 volumio-jan sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:11:02 volumio-jan volumio[3893]: info: Loading plugin "upnp_browser"... Dec 16 22:11:02 volumio-jan sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:02 volumio-jan sudo[3929]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:02 volumio-jan sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:11:02 volumio-jan sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:02 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:11:02 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:11:02 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:11:02 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:11:03 volumio-jan volumio[3893]: info: Loading plugin "networkfs"... Dec 16 22:11:03 volumio-jan dhcpcd[783]: wlan0: probing address 192.168.0.7/24 Dec 16 22:11:03 volumio-jan volumio[3893]: info: Starting Udev Watcher for removable devices Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: RECOVERY Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: volumio_data Dec 16 22:11:03 volumio-jan volumio[3893]: error: Cannot associate FS Label, not mounting Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: SETTINGS Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: boot-rbp4 Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: root-rbp4 Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: boot Dec 16 22:11:03 volumio-jan volumio[3893]: info: Ignoring mount for partition: volumio Dec 16 22:11:03 volumio-jan mpd[3936]: Dec 16 22:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:11:03 volumio-jan volumio[3893]: info: Mounting Device PENDRIVE Dec 16 22:11:03 volumio-jan sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PENDRIVE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:11:03 volumio-jan sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:03 volumio-jan sudo[3954]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:03 volumio-jan volumio[3893]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:11:03 volumio-jan volumio[3893]: error: Failed to mount PENDRIVE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PENDRIVE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:11:03 volumio-jan volumio[3893]: mount: /media/PENDRIVE: /dev/sda1 already mounted on /media/PENDRIVE. Dec 16 22:11:03 volumio-jan volumio[3893]: info: Mounting Device MyBook Dec 16 22:11:03 volumio-jan mpd[3936]: Dec 16 22:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:11:03 volumio-jan mpd[3936]: Dec 16 22:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:11:03 volumio-jan systemd[1]: Started Music Player Daemon. Dec 16 22:11:03 volumio-jan sudo[3931]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:03 volumio-jan sudo[3962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MyBook -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:11:03 volumio-jan sudo[3962]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:04 volumio-jan ntfs-3g[3966]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 16 22:11:04 volumio-jan ntfs-3g[3966]: Mounted /dev/sda2 (Read-Write, label "MyBook", NTFS 3.1) Dec 16 22:11:04 volumio-jan ntfs-3g[3966]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 16 22:11:04 volumio-jan ntfs-3g[3966]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Dec 16 22:11:04 volumio-jan ntfs-3g[3966]: Global ownership and permissions enforced, configuration type 7 Dec 16 22:11:04 volumio-jan sudo[3962]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:04 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "alarm-clock"... Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "airplay_emulation"... Dec 16 22:11:04 volumio-jan volumio[3893]: info: Starting Shairport Sync Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "last_100"... Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "webradio"... Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "i2s_dacs"... Dec 16 22:11:04 volumio-jan volumio[3893]: info: I2S DAC not set, start Auto-detection Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "volumiodiscovery"... Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** For more information see Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:11:04 volumio-jan volumio[3893]: *** WARNING *** For more information see Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** For more information see Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 22:11:04 volumio-jan node[3893]: *** WARNING *** For more information see Dec 16 22:11:04 volumio-jan volumio[3893]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 22:11:04 volumio-jan volumio[3893]: info: Discovery: Started advertising with name: Volumio-jan Dec 16 22:11:04 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 22:11:04 volumio-jan volumio[3893]: info: Plugin autostart is not enabled Dec 16 22:11:04 volumio-jan volumio[3893]: info: Loading plugin "now_playing"... Dec 16 22:11:04 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:11:04] [connect] Successful connection Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "outputs"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "albumart"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Plugin example_plugin is not enabled Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "inputs"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "updater_comm"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Plugin mpdemulation is not enabled Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "rest_api"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "websocket"... Dec 16 22:11:05 volumio-jan volumio[3893]: info: Plugin gpio_control is not enabled Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "rotaryencoder2"... Dec 16 22:11:05 volumio-jan volumio[3893]: Forking 3 albumart workers Dec 16 22:11:05 volumio-jan volumio[3893]: info: Loading plugin "lastfm"... Dec 16 22:11:05 volumio-jan sudo[3922]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:06 volumio-jan volumio[3893]: info: Loading plugin "touch_display"... Dec 16 22:11:06 volumio-jan volumio[3893]: Starting albumart workers Dec 16 22:11:06 volumio-jan volumio[3893]: Starting albumart workers Dec 16 22:11:06 volumio-jan volumio[3893]: Starting albumart workers Dec 16 22:11:06 volumio-jan volumio[3893]: info: Applying required configuration parameters for plugin touch_display Dec 16 22:11:06 volumio-jan volumio[3893]: info: Loading i18n strings for locale it Dec 16 22:11:06 volumio-jan volumio[3893]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:11:06 volumio-jan volumio[3893]: Updating browse sources language Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::initPlayerControls Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: Express server listening on port 3000 Dec 16 22:11:06 volumio-jan volumio[3893]: [Metrics] WebUI: 5s 632.60ms Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::resetVolumioState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::getcurrentVolume Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: MPD Permissions set Dec 16 22:11:06 volumio-jan volumio-remote-updater[530]: [2025-12-16 22:11:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765919464 101 Dec 16 22:11:06 volumio-jan volumio[3893]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 16 22:11:06 volumio-jan volumio[3893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 16 22:11:06 volumio-jan volumio[3893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 16 22:11:06 volumio-jan volumio[3893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 16 22:11:06 volumio-jan volumio[3893]: info: MPD running with PID3936 Dec 16 22:11:06 volumio-jan volumio[3893]: ,establishing connection Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:06 volumio-jan volumio[3893]: info: Reloading queue from file Dec 16 22:11:06 volumio-jan volumio[3893]: error: updateQueue error: null Dec 16 22:11:06 volumio-jan volumio[3893]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::pushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CorePlayQueue::getTrack 0 Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioPushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::updateTrackBlock Dec 16 22:11:06 volumio-jan volumio[3893]: info: CorePlayQueue::getTrackBlock Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::setRepeat false single undefined Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::pushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CorePlayQueue::getTrack 0 Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioPushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::setRandom false Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreStateMachine::pushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: CorePlayQueue::getTrack 0 Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:11:06 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioPushState Dec 16 22:11:06 volumio-jan volumio[3893]: info: Setting Device type: Raspberry PI Dec 16 22:11:06 volumio-jan volumio[3893]: error: updateQueue error: null Dec 16 22:11:06 volumio-jan volumio[3893]: info: Completed loading Core Plugins Dec 16 22:11:06 volumio-jan volumio[3893]: info: Preparing to generate the ALSA configuration file Dec 16 22:11:07 volumio-jan volumio[3893]: info: VolumeController:: Volume=76 Mute =false Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreStateMachine::pushState Dec 16 22:11:07 volumio-jan volumio[3893]: info: CorePlayQueue::getTrack 0 Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioPushState Dec 16 22:11:07 volumio-jan volumio[3893]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 16 22:11:07 volumio-jan volumio[3893]: info: Reading ALSA contributions from plugins. Dec 16 22:11:07 volumio-jan volumio[3893]: info: Asound.conf file unchanged, so no further update is needed Dec 16 22:11:07 volumio-jan volumio[3893]: info: Output device has changed, restarting MPD Dec 16 22:11:07 volumio-jan sudo[4040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 22:11:07 volumio-jan sudo[4040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:07 volumio-jan volumio[3893]: info: Output device has changed, restarting Shairport Sync Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:07 volumio-jan sudo[4040]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:07 volumio-jan sudo[4043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 22:11:07 volumio-jan sudo[4043]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:07 volumio-jan systemd[1]: Stopping Music Player Daemon... Dec 16 22:11:07 volumio-jan volumio[3893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:11:07 volumio-jan volumio[3893]: info: ___________ START PLUGINS ___________ Dec 16 22:11:07 volumio-jan volumio[3893]: info: Loading i18n strings for locale it Dec 16 22:11:07 volumio-jan systemd[1]: mpd.service: Succeeded. Dec 16 22:11:07 volumio-jan systemd[1]: Stopped Music Player Daemon. Dec 16 22:11:07 volumio-jan volumio[3893]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:11:07 volumio-jan systemd[1]: Starting Music Player Daemon... Dec 16 22:11:07 volumio-jan volumio[3893]: info: /tmp/myfifo created Dec 16 22:11:07 volumio-jan sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Dec 16 22:11:07 volumio-jan sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:07 volumio-jan sudo[4052]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:07 volumio-jan volumio[3893]: info: snd-dummy loaded Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:11:07 volumio-jan volumio[3893]: info: [1765919467226] CoreMusicLibrary::Adding element Media Servers Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 22:11:07 volumio-jan volumio[3893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:11:07 volumio-jan volumio[3893]: info: [1765919467322] CoreMusicLibrary::Adding element Last_100 Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:11:07 volumio-jan volumio[3893]: info: [1765919467324] CoreMusicLibrary::Adding element Webradio Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:11:07 volumio-jan volumio[3893]: info: [now-playing-config] Config is up to date. Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:11:07 volumio-jan volumio[3893]: info: Discovery: Getting this device information Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioGetState Dec 16 22:11:07 volumio-jan volumio[3893]: info: CorePlayQueue::getTrack 0 Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 22:11:07 volumio-jan volumio[3893]: info: [1765919467369] CoreMusicLibrary::Adding element LastFM Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:11:07 volumio-jan volumio[3893]: Cannot find translation for source LastFM Dec 16 22:11:07 volumio-jan volumio[3893]: info: [LastFM] scrobbler initiated! Dec 16 22:11:07 volumio-jan volumio[3893]: info: [LastFM] extended logging: false Dec 16 22:11:07 volumio-jan volumio[3893]: info: [LastFM] try scrobble stream/radio plays: true Dec 16 22:11:07 volumio-jan volumio[3893]: info: [LastFM] Left init routine Dec 16 22:11:07 volumio-jan volumio[3893]: info: [LastFM] Socket already connected: true Dec 16 22:11:07 volumio-jan volumio[3893]: info: Loading i18n strings for locale it Dec 16 22:11:07 volumio-jan volumio[3893]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 16 22:11:07 volumio-jan volumio[3893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:11:07 volumio-jan volumio[3893]: info: Volumio Calling Home Dec 16 22:11:07 volumio-jan volumio[3893]: info: Preparing to generate the ALSA configuration file Dec 16 22:11:07 volumio-jan volumio[3893]: info: [now-playing-app] App is listening on port 4004. Dec 16 22:11:07 volumio-jan sudo[4070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Dec 16 22:11:07 volumio-jan sudo[4070]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:07 volumio-jan sudo[4073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 22:11:07 volumio-jan sudo[4073]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:07 volumio-jan sudo[4070]: pam_unix(sudo:session): session closed for user root Dec 16 22:11:07 volumio-jan systemd[1]: Reloading. Dec 16 22:11:07 volumio-jan volumio[3893]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 22:11:07 volumio-jan volumio[3893]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 22:11:07 volumio-jan volumio[3893]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 22:11:07 volumio-jan volumio[3893]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 22:11:07 volumio-jan volumio[3893]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 22:11:07 volumio-jan volumio[3893]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 22:11:07 volumio-jan volumio[3893]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 22:11:07 volumio-jan volumio[3893]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 22:11:07 volumio-jan volumio[3893]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 22:11:07 volumio-jan volumio[3893]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 22:11:07 volumio-jan volumio[3893]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 22:11:07 volumio-jan volumio[3893]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 22:11:07 volumio-jan volumio[3893]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 22:11:07 volumio-jan volumio[3893]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 22:11:07 volumio-jan volumio[3893]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 22:11:07 volumio-jan volumio[3893]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 22:11:07 volumio-jan volumio[3893]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 22:11:07 volumio-jan volumio[3893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:11:07 volumio-jan volumio[3893]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 16 22:11:07 volumio-jan volumio[3893]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 16 22:11:07 volumio-jan volumio[3893]: errno: -16, Dec 16 22:11:07 volumio-jan volumio[3893]: code: 'EBUSY', Dec 16 22:11:07 volumio-jan volumio[3893]: syscall: 'getaddrinfo', Dec 16 22:11:07 volumio-jan volumio[3893]: hostname: 'ws.audioscrobbler.com' Dec 16 22:11:07 volumio-jan volumio[3893]: } Dec 16 22:11:07 volumio-jan volumio[3893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:11:07 volumio-jan systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 16 22:11:07 volumio-jan mpd[4062]: Dec 16 22:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 22:11:08 volumio-jan mpd[4062]: Dec 16 22:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 16 22:11:08 volumio-jan mpd[4062]: Dec 16 22:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 16 22:11:08 volumio-jan sudo[4105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 22:10 Dec 16 22:11:08 volumio-jan sudo[4105]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 22:11:08 volumio-jan dhcpcd[783]: wlan0: leased 192.168.0.7 for 3600 seconds Dec 16 22:11:08 volumio-jan avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.7. Dec 16 22:11:08 volumio-jan dhcpcd[783]: wlan0: adding route to 192.168.0.0/24 Dec 16 22:11:08 volumio-jan dhcpcd[783]: wlan0: adding default route via 192.168.0.254 Dec 16 22:11:08 volumio-jan avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 22:11:08 volumio-jan avahi-daemon[533]: Registering new address record for 192.168.0.7 on wlan0.IPv4. 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"