-- Logs begin at Wed 2025-04-23 16:40:29 CEST, end at Wed 2025-04-23 17:52:42 CEST. -- Apr 23 17:52:22 ts-464sparade-filer systemd-timedated[991]: Changed local time to Wed Apr 23 17:52:22 2025 Apr 23 17:52:22 ts-464sparade-filer sudo[989]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:22 ts-464sparade-filer volumio-time-update[550]: volumio-time-update-util: System time updated successfully. Apr 23 17:52:22 ts-464sparade-filer systemd[1]: Started Volumio Time Update Utility. Apr 23 17:52:22 ts-464sparade-filer ntpd[780]: Listen normally on 3 wlan0 192.168.0.36:123 Apr 23 17:52:22 ts-464sparade-filer ntpd[780]: new interface(s) found: waking up resolver Apr 23 17:52:22 ts-464sparade-filer kernel: sched: RT throttling activated Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: [2025/04/23 17:52:23.517220, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 23 17:52:23 ts-464sparade-filer systemd[1]: Started Samba NMB Daemon. Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: [2025/04/23 17:52:23.541959, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: register_name_response: Answer name TS-464_SPARADE<20> differs from question name TS-464_SPARADE <20>. Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: [2025/04/23 17:52:23.542795, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: register_name_response: Answer name TS-464_SPARADE<03> differs from question name TS-464_SPARADE <03>. Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: [2025/04/23 17:52:23.543306, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Apr 23 17:52:23 ts-464sparade-filer nmbd[778]: register_name_response: Answer name TS-464_SPARADE<00> differs from question name TS-464_SPARADE <00>. Apr 23 17:52:23 ts-464sparade-filer systemd[1]: Starting Samba Winbind Daemon... Apr 23 17:52:24 ts-464sparade-filer winbindd[993]: [2025/04/23 17:52:24.014545, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 23 17:52:24 ts-464sparade-filer winbindd[993]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 23 17:52:24 ts-464sparade-filer winbindd[993]: [2025/04/23 17:52:24.039461, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 23 17:52:24 ts-464sparade-filer winbindd[993]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Started Samba Winbind Daemon. Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Starting Samba SMB Daemon... Apr 23 17:52:24 ts-464sparade-filer smbd[998]: [2025/04/23 17:52:24.771372, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Started Samba SMB Daemon. Apr 23 17:52:24 ts-464sparade-filer smbd[998]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Reached target Multi-User System. Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Reached target Graphical Interface. Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 23 17:52:24 ts-464sparade-filer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 23 17:52:24 ts-464sparade-filer systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 23 17:52:24 ts-464sparade-filer volumio[967]: info: ------------------------------------------- Apr 23 17:52:25 ts-464sparade-filer volumio[967]: info: ----- Volumio3 ---- Apr 23 17:52:25 ts-464sparade-filer volumio[967]: info: ------------------------------------------- Apr 23 17:52:25 ts-464sparade-filer volumio[967]: info: ----- System startup ---- Apr 23 17:52:25 ts-464sparade-filer volumio[967]: info: ------------------------------------------- Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: MYVOLUMIO Environment detected Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Plugin folders cleanup Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning into folder /volumio/app/plugins/ Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category audio_interface Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category miscellanea Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category music_service Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category plugins.json Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category system_controller Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning category user_interface Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Scanning into folder /data/plugins/ Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Plugin folders cleanup completed Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: ------------------------------------------- Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: ----- Core plugins startup ---- Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: ------------------------------------------- Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Loading plugins from folder /volumio/app/plugins/ Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Adding plugin upnp to MyMusic Plugins Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Loading plugins from folder /data/plugins/ Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Loading plugin "system"... Apr 23 17:52:26 ts-464sparade-filer volumio[967]: info: Loading plugin "appearance"... Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "network"... Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Refreshing Cached IP Addresses Apr 23 17:52:28 ts-464sparade-filer sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 17:52:28 ts-464sparade-filer sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 17:52:28 ts-464sparade-filer sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:28 ts-464sparade-filer sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "services"... Apr 23 17:52:28 ts-464sparade-filer sudo[1021]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "alsa_controller"... Apr 23 17:52:28 ts-464sparade-filer sudo[1023]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:28 ts-464sparade-filer sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 23 17:52:28 ts-464sparade-filer sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:28 ts-464sparade-filer volumio-remote-updater[554]: [2025-04-23 17:52:28] [connect] Successful connection Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "wizard"... Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "networkfs"... Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Starting Udev Watcher for removable devices Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Ignoring mount for partition: boot Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Ignoring mount for partition: volumio Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Ignoring mount for partition: volumio_data Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "volumio_command_line_client"... Apr 23 17:52:28 ts-464sparade-filer sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=NyNAS,password=adm.NyNAS/24,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.12/Egen musik\\Sparade filer /mnt/NAS/Sparade_filer Apr 23 17:52:28 ts-464sparade-filer sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:28 ts-464sparade-filer volumio[967]: info: Loading plugin "upnp"... Apr 23 17:52:29 ts-464sparade-filer volumio[967]: info: [1745423549008] Starting Upmpd Daemon Apr 23 17:52:29 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 23 17:52:29 ts-464sparade-filer volumio[967]: info: Loading plugin "my_music"... Apr 23 17:52:29 ts-464sparade-filer volumio[967]: info: Loading plugin "mpd"... Apr 23 17:52:29 ts-464sparade-filer kernel: Key type cifs.spnego registered Apr 23 17:52:29 ts-464sparade-filer kernel: Key type cifs.idmap registered Apr 23 17:52:29 ts-464sparade-filer kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 23 17:52:29 ts-464sparade-filer kernel: CIFS: Attempting to mount //192.168.0.12/Egen musik/Sparade filer Apr 23 17:52:29 ts-464sparade-filer sudo[1032]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:29 ts-464sparade-filer sudo[1049]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:29 ts-464sparade-filer systemd[1]: Started Music Player Daemon. Apr 23 17:52:29 ts-464sparade-filer systemd[1]: Startup finished in 13.485s (kernel) + 28.996s (userspace) = 42.482s. Apr 23 17:52:29 ts-464sparade-filer mpd_monitor.sh[566]: MPD Monitor Service: MPD restarted due to no mpc output. Apr 23 17:52:29 ts-464sparade-filer volumio[967]: info: Loading plugin "upnp_browser"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "alarm-clock"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "airplay_emulation"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Starting Shairport Sync Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "last_100"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "webradio"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "i2s_dacs"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: I2S DAC not set, start Auto-detection Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "volumiodiscovery"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** For more information see Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 23 17:52:30 ts-464sparade-filer volumio[967]: *** WARNING *** For more information see Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** For more information see Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 23 17:52:30 ts-464sparade-filer node[967]: *** WARNING *** For more information see Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Discovery: Started advertising with name: TS-464_Sparade filer Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "outputs"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "albumart"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Plugin example_plugin is not enabled Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "inputs"... Apr 23 17:52:30 ts-464sparade-filer volumio[967]: info: Loading plugin "updater_comm"... Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Plugin mpdemulation is not enabled Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Loading plugin "rest_api"... Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Loading plugin "websocket"... Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Starting Socket.io Server version 2.3.0 Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Loading i18n strings for locale sv Apr 23 17:52:31 ts-464sparade-filer volumio[967]: Updating browse sources language Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:52:31 ts-464sparade-filer volumio[967]: Forking 3 albumart workers Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::initPlayerControls Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: Express server listening on port 3000 Apr 23 17:52:31 ts-464sparade-filer volumio[967]: [Metrics] WebUI: 8s 133.72ms Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreStateMachine::resetVolumioState Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreStateMachine::getcurrentVolume Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:31 ts-464sparade-filer volumio[967]: info: Volumio Network Manager: Network status updated: 2 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 192.168.0.36 from 192.168.0.13 UA: Mozilla/5.0 (Linux; Android 14; KB2003 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 192.168.0.36:3000 from 192.168.0.26 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 23 17:52:32 ts-464sparade-filer volumio-remote-updater[554]: [2025-04-23 17:52:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745423548 101 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 192.168.0.36 from 192.168.0.13 UA: Mozilla/5.0 (Linux; Android 14; KB2003 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: VolumeController:: Volume=77 Mute =false Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::pushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioPushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::updateTrackBlock Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrackBlock Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Setting Device type: Raspberry PI Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::setRepeat true single undefined Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::pushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioPushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::setRandom true Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::pushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioPushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: Starting albumart workers Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Completed loading Core Plugins Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Preparing to generate the ALSA configuration file Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Discovery: adding 21030c35-e294-46c8-bb30-23e1d1fc7ada Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Discovery: Found device TS-464_Sparade filer Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Discovery: adding cdde2820-bbb2-4976-b0c8-29530c535837 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Discovery: Found device Volumio Apr 23 17:52:32 ts-464sparade-filer volumio[967]: Starting albumart workers Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Discovery: Connecting to remote: 192.168.0.26 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: VolumeController:: Volume=77 Mute =false Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreStateMachine::pushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioPushState Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Asound.conf file unchanged, so no further update is needed Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Output device has changed, restarting MPD Apr 23 17:52:32 ts-464sparade-filer volumio[967]: Starting albumart workers Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Output device has changed, restarting Shairport Sync Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:32 ts-464sparade-filer sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 17:52:32 ts-464sparade-filer sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:32 ts-464sparade-filer sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 17:52:32 ts-464sparade-filer sudo[1122]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:32 ts-464sparade-filer sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: ___________ START PLUGINS ___________ Apr 23 17:52:32 ts-464sparade-filer systemd[1]: Stopping Music Player Daemon... Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: ControllerMpd::onStart: Initializing MPD Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Creating MPD Configuration file Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: [1745423552763] CoreMusicLibrary::Adding element Mediaservrar Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:52:32 ts-464sparade-filer sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 17:52:32 ts-464sparade-filer sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:32 ts-464sparade-filer sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 17:52:32 ts-464sparade-filer sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:32 ts-464sparade-filer sudo[1131]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:32 ts-464sparade-filer systemd[1]: mpd.service: Succeeded. Apr 23 17:52:32 ts-464sparade-filer systemd[1]: Stopped Music Player Daemon. Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 23 17:52:32 ts-464sparade-filer volumio[967]: info: [1745423552998] CoreMusicLibrary::Adding element Last_100 Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: [1745423553008] CoreMusicLibrary::Adding element Webradio Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 17:52:33 ts-464sparade-filer systemd[1]: Starting Music Player Daemon... Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: Initializing BBC Radios Apr 23 17:52:33 ts-464sparade-filer sudo[1142]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 17:52:33 ts-464sparade-filer sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:33 ts-464sparade-filer sudo[1142]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: Volumio Calling Home Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: Discovery: Connected to remote: 192.168.0.26 Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: MPD Permissions set Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: MPD Permissions set Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: Volumio called home Apr 23 17:52:33 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 17:52:33 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 17:52:33 ts-464sparade-filer systemd[1]: systemd-fsckd.service: Succeeded. Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Starting Shairport Sync Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Starting Shairport Sync Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Starting Shairport Sync Apr 23 17:52:34 ts-464sparade-filer sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 17:52:34 ts-464sparade-filer sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:34 ts-464sparade-filer sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 17:52:34 ts-464sparade-filer sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:34 ts-464sparade-filer sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 23 17:52:34 ts-464sparade-filer sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:34 ts-464sparade-filer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 23 17:52:34 ts-464sparade-filer systemd[1]: shairport-sync.service: Succeeded. Apr 23 17:52:34 ts-464sparade-filer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 23 17:52:34 ts-464sparade-filer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 23 17:52:34 ts-464sparade-filer sudo[1191]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:34 ts-464sparade-filer sudo[1193]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:34 ts-464sparade-filer sudo[1195]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Reloading queue from file Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Shairport-Sync Started Apr 23 17:52:34 ts-464sparade-filer volumio[967]: Error adding Membership: Error: addMembership EINVAL Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Shairport-Sync Started Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: Shairport-Sync Started Apr 23 17:52:34 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 192.168.0.36:3000 from 192.168.0.26 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 23 17:52:34 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:35 ts-464sparade-filer mpd[1154]: Apr 23 17:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 23 17:52:35 ts-464sparade-filer sh[501]: timed out Apr 23 17:52:35 ts-464sparade-filer dhcpcd[521]: timed out Apr 23 17:52:35 ts-464sparade-filer sh[501]: dhcpcd exited Apr 23 17:52:35 ts-464sparade-filer dhcpcd[521]: dhcpcd exited Apr 23 17:52:35 ts-464sparade-filer sh[501]: ifup: failed to bring up eth0 Apr 23 17:52:35 ts-464sparade-filer systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 23 17:52:35 ts-464sparade-filer systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 23 17:52:35 ts-464sparade-filer volumio[967]: verbose: New Socket.io Connection to 192.168.0.36 from 192.168.0.13 UA: Mozilla/5.0 (Linux; Android 14; KB2003 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetVisibleSources Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: Received Get System Info Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: Discovery: Getting this device information Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetState Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getTrack 0 Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: Listing playlists Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::volumioGetQueue Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CoreStateMachine::getQueue Apr 23 17:52:35 ts-464sparade-filer volumio[967]: info: CorePlayQueue::getQueue Apr 23 17:52:37 ts-464sparade-filer systemd[1]: systemd-hostnamed.service: Succeeded. Apr 23 17:52:38 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 17:52:39 ts-464sparade-filer sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 17:52:39 ts-464sparade-filer sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:39 ts-464sparade-filer sudo[1222]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:39 ts-464sparade-filer sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 17:52:39 ts-464sparade-filer sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:39 ts-464sparade-filer sudo[1224]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:39 ts-464sparade-filer sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 23 17:52:39 ts-464sparade-filer sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:52:39 ts-464sparade-filer systemd[1]: Started UPnP Renderer front-end to MPD. Apr 23 17:52:39 ts-464sparade-filer sudo[1228]: pam_unix(sudo:session): session closed for user root Apr 23 17:52:39 ts-464sparade-filer volumio[967]: info: Upmpdcli Daemon Started Apr 23 17:52:41 ts-464sparade-filer volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 23 17:52:41 ts-464sparade-filer volumio[967]: verbose: ControllerMpd::sendMpdCommand rescan Apr 23 17:52:41 ts-464sparade-filer volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 17:52:41 ts-464sparade-filer volumio[967]: TypeError: Cannot read property 'then' of undefined Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at Socket.emit (events.js:315:20) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 23 17:52:41 ts-464sparade-filer volumio[967]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 23 17:52:41 ts-464sparade-filer volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 17:52:42 ts-464sparade-filer sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 17:51 Apr 23 17:52:42 ts-464sparade-filer sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"