-- Logs begin at Thu 2025-06-12 04:37:28 CEST, end at Sun 2025-06-22 19:13:49 CEST. -- Jun 22 19:12:51 wc systemd-timedated[827]: Changed local time to Sun Jun 22 19:12:51 2025 Jun 22 19:12:51 wc sudo[825]: pam_unix(sudo:session): session closed for user root Jun 22 19:12:51 wc volumio-time-update[415]: volumio-time-update-util: System time updated successfully. Jun 22 19:12:51 wc systemd[1]: Starting Daily apt download activities... Jun 22 19:12:51 wc systemd[1]: Started Volumio Time Update Utility. Jun 22 19:12:51 wc nmbd[532]: [2025/06/22 19:12:51.189685, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 22 19:12:51 wc nmbd[532]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 22 19:12:51 wc systemd[1]: Started Samba NMB Daemon. Jun 22 19:12:51 wc systemd[1]: Starting Samba Winbind Daemon... Jun 22 19:12:51 wc nmbd[532]: [2025/06/22 19:12:51.394184, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 22 19:12:51 wc nmbd[532]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.80 for name WORKGROUP<1d>. Jun 22 19:12:51 wc nmbd[532]: This response was from IP 192.168.0.242, reporting an IP address of 192.168.0.242. Jun 22 19:12:54 wc winbindd[830]: [2025/06/22 19:12:54.892175, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 22 19:12:54 wc winbindd[830]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 22 19:12:55 wc systemd[1]: Started Samba Winbind Daemon. Jun 22 19:12:55 wc winbindd[830]: [2025/06/22 19:12:55.014867, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 22 19:12:55 wc winbindd[830]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 22 19:12:55 wc systemd[1]: Starting Samba SMB Daemon... Jun 22 19:12:58 wc systemd[1]: apt-daily.service: Succeeded. Jun 22 19:12:58 wc systemd[1]: Started Daily apt download activities. Jun 22 19:12:58 wc systemd[1]: Starting Daily apt upgrade and clean activities... Jun 22 19:12:59 wc volumio-remote-updater[402]: [2025-06-22 19:12:59] [info] asio async_connect error: system:111 (Connection refused) Jun 22 19:12:59 wc volumio-remote-updater[402]: [2025-06-22 19:12:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 22 19:12:59 wc volumio-remote-updater[402]: [2025-06-22 19:12:59] [error] handle_connect error: Underlying Transport Error Jun 22 19:13:00 wc smbd[858]: [2025/06/22 19:13:00.470929, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 22 19:13:00 wc smbd[858]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 22 19:13:00 wc systemd[1]: Started Samba SMB Daemon. Jun 22 19:13:00 wc systemd[1]: Reached target Multi-User System. Jun 22 19:13:00 wc systemd[1]: Reached target Graphical Interface. Jun 22 19:13:00 wc systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 22 19:13:01 wc systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 22 19:13:01 wc systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 22 19:13:02 wc systemd[1]: systemd-hostnamed.service: Succeeded. Jun 22 19:13:03 wc systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 22 19:13:03 wc systemd[1]: Started Daily apt upgrade and clean activities. Jun 22 19:13:03 wc systemd[1]: Startup finished in 21.156s (kernel) + 1min 18.105s (userspace) = 1min 39.261s. Jun 22 19:13:05 wc volumio[804]: info: ------------------------------------------- Jun 22 19:13:05 wc volumio[804]: info: ----- Volumio3 ---- Jun 22 19:13:05 wc volumio[804]: info: ------------------------------------------- Jun 22 19:13:05 wc volumio[804]: info: ----- System startup ---- Jun 22 19:13:05 wc volumio[804]: info: ------------------------------------------- Jun 22 19:13:09 wc volumio-remote-updater[402]: [2025-06-22 19:13:09] [connect] Successful connection Jun 22 19:13:09 wc volumio[804]: info: MYVOLUMIO Environment detected Jun 22 19:13:10 wc volumio[804]: info: Plugin folders cleanup Jun 22 19:13:10 wc volumio[804]: info: Scanning into folder /volumio/app/plugins/ Jun 22 19:13:10 wc volumio[804]: info: Scanning category audio_interface Jun 22 19:13:10 wc volumio[804]: info: Scanning category miscellanea Jun 22 19:13:10 wc volumio[804]: info: Scanning category music_service Jun 22 19:13:10 wc volumio[804]: info: Scanning category plugins.json Jun 22 19:13:10 wc volumio[804]: info: Scanning category system_controller Jun 22 19:13:10 wc volumio[804]: info: Scanning category user_interface Jun 22 19:13:10 wc volumio[804]: info: Scanning into folder /data/plugins/ Jun 22 19:13:10 wc volumio[804]: info: Scanning category music_service Jun 22 19:13:10 wc volumio[804]: info: Plugin folders cleanup completed Jun 22 19:13:10 wc volumio[804]: info: ------------------------------------------- Jun 22 19:13:10 wc volumio[804]: info: ----- Core plugins startup ---- Jun 22 19:13:10 wc volumio[804]: info: ------------------------------------------- Jun 22 19:13:10 wc volumio[804]: info: Loading plugins from folder /volumio/app/plugins/ Jun 22 19:13:10 wc volumio[804]: info: Adding plugin upnp to MyMusic Plugins Jun 22 19:13:10 wc volumio[804]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 22 19:13:10 wc volumio[804]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 22 19:13:10 wc volumio[804]: info: Loading plugins from folder /data/plugins/ Jun 22 19:13:10 wc volumio[804]: info: Loading plugin "system"... Jun 22 19:13:10 wc volumio[804]: info: Loading plugin "appearance"... Jun 22 19:13:16 wc volumio[804]: info: Loading plugin "network"... Jun 22 19:13:16 wc volumio[804]: info: Refreshing Cached IP Addresses Jun 22 19:13:16 wc sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 22 19:13:16 wc sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:16 wc volumio[804]: info: Loading plugin "services"... Jun 22 19:13:16 wc sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 22 19:13:16 wc sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:16 wc volumio[804]: info: Loading plugin "alsa_controller"... Jun 22 19:13:16 wc sudo[994]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:16 wc sudo[996]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:17 wc sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 22 19:13:17 wc sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:17 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 19:13:17 wc volumio[804]: info: Loading plugin "wizard"... Jun 22 19:13:17 wc volumio[804]: info: Loading plugin "networkfs"... Jun 22 19:13:17 wc volumio[804]: info: Starting Udev Watcher for removable devices Jun 22 19:13:17 wc sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=1216351341,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.242/SAMBA /mnt/NAS/SAMBA Jun 22 19:13:17 wc sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:18 wc volumio[804]: info: Ignoring mount for partition: boot Jun 22 19:13:18 wc volumio[804]: info: Ignoring mount for partition: volumio Jun 22 19:13:18 wc volumio[804]: info: Ignoring mount for partition: volumio_data Jun 22 19:13:18 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 22 19:13:18 wc volumio[804]: info: Loading plugin "volumio_command_line_client"... Jun 22 19:13:18 wc sudo[1002]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:18 wc volumio[804]: info: Plugin upnp is not enabled Jun 22 19:13:18 wc volumio[804]: info: Loading plugin "my_music"... Jun 22 19:13:18 wc volumio[804]: info: Loading plugin "mpd"... Jun 22 19:13:18 wc kernel: Key type cifs.spnego registered Jun 22 19:13:18 wc kernel: Key type cifs.idmap registered Jun 22 19:13:18 wc 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. Jun 22 19:13:18 wc kernel: CIFS: Attempting to mount //192.168.0.242/SAMBA Jun 22 19:13:19 wc sudo[1022]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:20 wc volumio[804]: info: Plugin upnp_browser is not enabled Jun 22 19:13:20 wc volumio[804]: info: Loading plugin "alarm-clock"... Jun 22 19:13:21 wc systemd[1]: systemd-timedated.service: Succeeded. Jun 22 19:13:21 wc volumio[804]: info: Plugin airplay_emulation is not enabled Jun 22 19:13:21 wc volumio[804]: info: Loading plugin "last_100"... Jun 22 19:13:21 wc volumio[804]: info: Loading plugin "webradio"... Jun 22 19:13:22 wc volumio[804]: info: Loading plugin "i2s_dacs"... Jun 22 19:13:22 wc volumio[804]: info: Loading plugin "volumiodiscovery"... Jun 22 19:13:22 wc volumio[804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 22 19:13:22 wc volumio[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 19:13:22 wc volumio[804]: *** WARNING *** For more information see Jun 22 19:13:22 wc volumio[804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 22 19:13:22 wc volumio[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 19:13:22 wc volumio[804]: *** WARNING *** For more information see Jun 22 19:13:22 wc node[804]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 22 19:13:22 wc node[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 19:13:22 wc node[804]: *** WARNING *** For more information see Jun 22 19:13:22 wc node[804]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 22 19:13:22 wc node[804]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 19:13:22 wc node[804]: *** WARNING *** For more information see Jun 22 19:13:22 wc volumio[804]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 22 19:13:22 wc volumio[804]: info: Discovery: Started advertising with name: WC Jun 22 19:13:22 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 22 19:13:22 wc volumio[804]: info: Plugin spop is not enabled Jun 22 19:13:22 wc volumio[804]: info: Loading plugin "outputs"... Jun 22 19:13:22 wc volumio[804]: info: Loading plugin "albumart"... Jun 22 19:13:23 wc volumio[804]: info: Plugin example_plugin is not enabled Jun 22 19:13:23 wc volumio[804]: info: Loading plugin "inputs"... Jun 22 19:13:23 wc volumio[804]: info: Loading plugin "updater_comm"... Jun 22 19:13:24 wc volumio-remote-updater[402]: [2025-06-22 19:13:24] [connect] Successful connection Jun 22 19:13:25 wc volumio[804]: info: Plugin mpdemulation is not enabled Jun 22 19:13:25 wc volumio[804]: info: Loading plugin "rest_api"... Jun 22 19:13:25 wc volumio[804]: info: Loading plugin "websocket"... Jun 22 19:13:25 wc ntpd[536]: Soliciting pool server 130.61.133.198 Jun 22 19:13:25 wc volumio[804]: info: Starting Socket.io Server version 2.3.0 Jun 22 19:13:26 wc volumio[804]: info: Loading i18n strings for locale de Jun 22 19:13:26 wc volumio[804]: Updating browse sources language Jun 22 19:13:26 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 19:13:26 wc volumio[804]: Forking 1 albumart workers Jun 22 19:13:27 wc ntpd[536]: Soliciting pool server 178.63.67.56 Jun 22 19:13:27 wc ntpd[536]: Soliciting pool server 192.248.187.154 Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::initPlayerControls Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 22 19:13:28 wc volumio[804]: Express server listening on port 3000 Jun 22 19:13:28 wc volumio[804]: [Metrics] WebUI: 27s 522.68ms Jun 22 19:13:28 wc volumio[804]: info: CoreStateMachine::resetVolumioState Jun 22 19:13:28 wc volumio[804]: info: CoreStateMachine::getcurrentVolume Jun 22 19:13:28 wc volumio[804]: info: CoreCommandRouter::volumioRetrievevolume Jun 22 19:13:28 wc ntpd[536]: Soliciting pool server 152.70.19.169 Jun 22 19:13:28 wc ntpd[536]: Soliciting pool server 194.50.19.117 Jun 22 19:13:29 wc volumio[804]: info: Volumio Network Manager: Network status updated: 2 Jun 22 19:13:29 wc ntpd[536]: Soliciting pool server 162.159.200.123 Jun 22 19:13:29 wc ntpd[536]: Soliciting pool server 185.252.140.125 Jun 22 19:13:29 wc ntpd[536]: Soliciting pool server 78.47.56.71 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.102 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.99 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.102 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.99 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.102 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 22 19:13:30 wc ntpd[536]: Soliciting pool server 5.1.73.50 Jun 22 19:13:30 wc ntpd[536]: Soliciting pool server 51.75.67.47 Jun 22 19:13:30 wc ntpd[536]: Soliciting pool server 79.133.44.138 Jun 22 19:13:30 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.99 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 22 19:13:31 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:31 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:31 wc volumio[804]: info: CoreStateMachine::updateTrackBlock Jun 22 19:13:31 wc volumio[804]: info: CorePlayQueue::getTrackBlock Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::volumioRetrievevolume Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:31 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:31 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:31 wc ntpd[536]: Soliciting pool server 45.9.61.155 Jun 22 19:13:31 wc ntpd[536]: Soliciting pool server 2003:a:47f:abe4::2 Jun 22 19:13:31 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 19:13:31 wc volumio[804]: info: Reloading queue from file Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:32 wc volumio[804]: info: Setting Device type: Raspberry PI Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::setRepeat null single undefined Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::setRandom true Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:32 wc volumio[804]: info: Jun 22 19:13:32 wc volumio[804]: ---------------------------- Client requests Volume 50 Jun 22 19:13:32 wc volumio[804]: info: VolumeController::SetAlsaVolume50 Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:32 wc volumio[804]: info: Jun 22 19:13:32 wc volumio[804]: ---------------------------- Client requests Volume 50 Jun 22 19:13:32 wc volumio[804]: info: VolumeController::SetAlsaVolume50 Jun 22 19:13:32 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:32 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:32 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:32 wc ntpd[536]: Soliciting pool server 85.214.38.116 Jun 22 19:13:32 wc volumio[804]: info: Completed loading Core Plugins Jun 22 19:13:33 wc volumio[804]: info: Preparing to generate the ALSA configuration file Jun 22 19:13:33 wc volumio[804]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 22 19:13:33 wc volumio[804]: info: Reading ALSA contributions from plugins. Jun 22 19:13:33 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.102 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 22 19:13:33 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 22 19:13:33 wc volumio[804]: info: Jun 22 19:13:33 wc volumio[804]: ---------------------------- Client requests Random true Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioRandom Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::writePlayerControls Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:33 wc volumio[804]: info: CoreStateMachine::setRandom true Jun 22 19:13:33 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:33 wc volumio[804]: info: Jun 22 19:13:33 wc volumio[804]: ---------------------------- Client requests Random true Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioRandom Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::writePlayerControls Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:33 wc volumio[804]: info: CoreStateMachine::setRandom true Jun 22 19:13:33 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:33 wc volumio[804]: info: Jun 22 19:13:33 wc volumio[804]: ---------------------------- Client requests Volume 50 Jun 22 19:13:33 wc volumio[804]: info: VolumeController::SetAlsaVolume50 Jun 22 19:13:33 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:33 wc volumio[804]: info: Discovery: adding 4eafa67a-dc6e-4837-9947-5761bc470cb8 Jun 22 19:13:33 wc volumio[804]: info: Discovery: Found device WC Jun 22 19:13:33 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:33 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:34 wc volumio[804]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.99 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 22 19:13:34 wc volumio[804]: info: Discovery: adding 6a4f79cd-e46c-4295-a6ea-3358ca887812 Jun 22 19:13:34 wc volumio[804]: info: Discovery: Found device Schlafzimmer Jun 22 19:13:34 wc volumio[804]: Starting albumart workers Jun 22 19:13:34 wc volumio[804]: info: Discovery: Connecting to remote: 192.168.0.102 Jun 22 19:13:34 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 22 19:13:34 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 22 19:13:34 wc volumio[804]: info: Jun 22 19:13:34 wc volumio[804]: ---------------------------- Client requests Volumio Play Playlist Lullaby Jun 22 19:13:34 wc volumio[804]: info: Play playlist Lullaby Jun 22 19:13:35 wc volumio[804]: info: Discovery: adding 06459c18-b434-48b2-8377-6ce0327572a0 Jun 22 19:13:35 wc volumio[804]: info: Discovery: Found device Tivoli Audio Jun 22 19:13:35 wc volumio[804]: info: Discovery: Connecting to remote: 192.168.0.99 Jun 22 19:13:35 wc volumio[804]: info: Jun 22 19:13:35 wc volumio[804]: ---------------------------- Client requests Volumio Play Playlist Lullaby Jun 22 19:13:35 wc volumio[804]: info: Play playlist Lullaby Jun 22 19:13:35 wc volumio[804]: info: Jun 22 19:13:35 wc volumio[804]: ---------------------------- Client requests Random true Jun 22 19:13:35 wc volumio[804]: info: CoreCommandRouter::volumioRandom Jun 22 19:13:35 wc volumio[804]: info: CoreCommandRouter::writePlayerControls Jun 22 19:13:35 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:35 wc volumio[804]: info: CoreStateMachine::setRandom true Jun 22 19:13:35 wc volumio[804]: info: CoreStateMachine::pushState Jun 22 19:13:35 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:35 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:35 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 22 19:13:35 wc volumio[804]: info: Discovery: adding 189c9483-7980-4071-bc76-5e11a79b3ad8 Jun 22 19:13:35 wc volumio[804]: info: Discovery: Found device Kinderzimmer Jun 22 19:13:35 wc volumio[804]: info: Discovery: Connecting to remote: 192.168.0.113 Jun 22 19:13:37 wc ntpd[536]: receive: Unexpected origin timestamp 0xec02b83f.d1d6e685 does not match aorg 0000000000.00000000 from server@85.214.38.116 xmt 0xec02b841.affd9c4c Jun 22 19:13:37 wc volumio[804]: info: Discovery: Connected to remote: 192.168.0.102 Jun 22 19:13:37 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:37 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:37 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:38 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:38 wc volumio[804]: info: Jun 22 19:13:38 wc volumio[804]: ---------------------------- Client requests Volumio Play Playlist Lullaby Jun 22 19:13:38 wc volumio[804]: info: Play playlist Lullaby Jun 22 19:13:38 wc volumio[804]: info: Discovery: Connected to remote: 192.168.0.99 Jun 22 19:13:38 wc volumio[804]: info: CoreCommandRouter::volumioGetState Jun 22 19:13:38 wc volumio[804]: info: CorePlayQueue::getTrack 0 Jun 22 19:13:38 wc volumio[804]: info: Discovery: Connected to remote: 192.168.0.113 Jun 22 19:13:38 wc volumio[804]: info: Asound.conf file unchanged, so no further update is needed Jun 22 19:13:38 wc volumio[804]: info: Output device has changed, restarting MPD Jun 22 19:13:39 wc volumio[804]: info: ___________ START PLUGINS ___________ Jun 22 19:13:39 wc volumio[804]: info: ControllerMpd::onStart: Initializing MPD Jun 22 19:13:39 wc sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 22 19:13:39 wc volumio[804]: info: Creating MPD Configuration file Jun 22 19:13:39 wc sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:39 wc sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 22 19:13:39 wc sudo[1121]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:39 wc sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:39 wc systemd[1]: Stopping Music Player Daemon... Jun 22 19:13:39 wc volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 19:13:39 wc systemd[1]: mpd.service: Succeeded. Jun 22 19:13:39 wc systemd[1]: Stopped Music Player Daemon. Jun 22 19:13:40 wc systemd[1]: Starting Music Player Daemon... Jun 22 19:13:40 wc volumio[804]: info: [1750612420058] CoreMusicLibrary::Adding element Last_100 Jun 22 19:13:40 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 19:13:40 wc sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 22 19:13:40 wc sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:40 wc volumio[804]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 19:13:40 wc volumio[804]: info: [1750612420427] CoreMusicLibrary::Adding element Webradio Jun 22 19:13:40 wc sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 22 19:13:40 wc sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:40 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 19:13:40 wc sudo[1127]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:40 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 19:13:40 wc sudo[1134]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 22 19:13:40 wc sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:40 wc sudo[1134]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:40 wc volumio[804]: info: Initializing BBC Radios Jun 22 19:13:40 wc systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 22 19:13:40 wc systemd[1]: mpd.service: Succeeded. Jun 22 19:13:41 wc systemd[1]: Stopped Music Player Daemon. Jun 22 19:13:41 wc systemd[1]: Starting Music Player Daemon... Jun 22 19:13:41 wc volumio[804]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 22 19:13:41 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 19:13:41 wc volumio-remote-updater[402]: [2025-06-22 19:13:41] [connect] Successful connection Jun 22 19:13:41 wc sudo[1150]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 22 19:13:41 wc sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 19:13:41 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 19:13:41 wc volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 19:13:41 wc sudo[1150]: pam_unix(sudo:session): session closed for user root Jun 22 19:13:41 wc volumio[804]: info: Volumio Calling Home Jun 22 19:13:44 wc volumio[804]: info: CoreCommandRouter::volumioClearQueue Jun 22 19:13:44 wc volumio[804]: info: CoreStateMachine::ClearQueue Jun 22 19:13:44 wc volumio[804]: info: CoreStateMachine::stop Jun 22 19:13:44 wc volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 19:13:44 wc volumio[804]: info: CorePlayQueue::clearPlayQueue Jun 22 19:13:44 wc volumio[804]: info: CorePlayQueue::saveQueue Jun 22 19:13:44 wc volumio[804]: info: CoreCommandRouter::volumioPushState Jun 22 19:13:44 wc volumio[804]: info: CoreCommandRouter::volumioPushQueue Jun 22 19:13:44 wc volumio[804]: info: CoreCommandRouter::volumioAddQueueItems Jun 22 19:13:44 wc volumio[804]: info: CoreStateMachine::addQueueItems Jun 22 19:13:44 wc volumio[804]: info: CorePlayQueue::addQueueItems Jun 22 19:13:44 wc volumio[804]: info: Preload queue cleared Jun 22 19:13:44 wc volumio[804]: info: Adding Item to queue: mnt/NAS/SAMBA/Lullaby/Hey Jude - Lullaby Renditions of The Beatles - Rockabye Baby!.mp3 Jun 22 19:13:44 wc volumio[804]: info: Exploding uri mnt/NAS/SAMBA/Lullaby/Hey Jude - Lullaby Renditions of The Beatles - Rockabye Baby!.mp3 in service mpd Jun 22 19:13:44 wc volumio[804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 19:13:44 wc volumio[804]: TypeError: Cannot read property 'then' of undefined Jun 22 19:13:44 wc volumio[804]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) Jun 22 19:13:44 wc volumio[804]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) Jun 22 19:13:44 wc volumio[804]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Jun 22 19:13:44 wc volumio[804]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jun 22 19:13:44 wc volumio[804]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jun 22 19:13:44 wc volumio[804]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Jun 22 19:13:44 wc volumio[804]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Jun 22 19:13:44 wc volumio[804]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) Jun 22 19:13:44 wc volumio[804]: at /volumio/app/playlistManager.js:726:30 Jun 22 19:13:44 wc volumio[804]: at /volumio/node_modules/jsonfile/index.js:46:5 Jun 22 19:13:44 wc volumio[804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 19:13:49 wc sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 19:12 Jun 22 19:13:49 wc sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"