-- Logs begin at Thu 2019-02-14 11:12:04 CET, end at Fri 2025-05-23 22:27:04 CEST. -- May 23 22:26:07 wc systemd-timedated[840]: Changed local time to Fri May 23 22:26:07 2025 May 23 22:26:07 wc sudo[836]: pam_unix(sudo:session): session closed for user root May 23 22:26:07 wc volumio-time-update[370]: volumio-time-update-util: System time updated successfully. May 23 22:26:07 wc systemd[1]: Starting Daily apt download activities... May 23 22:26:07 wc systemd[1]: Started Volumio Time Update Utility. May 23 22:26:07 wc nmbd[524]: [2025/05/23 22:26:07.671796, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 23 22:26:07 wc nmbd[524]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.80 for name WORKGROUP<1d>. May 23 22:26:07 wc nmbd[524]: This response was from IP 192.168.0.242, reporting an IP address of 192.168.0.242. May 23 22:26:08 wc volumio-remote-updater[366]: [2025-05-23 22:26:08] [info] asio async_connect error: system:111 (Connection refused) May 23 22:26:08 wc volumio-remote-updater[366]: [2025-05-23 22:26:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 23 22:26:08 wc volumio-remote-updater[366]: [2025-05-23 22:26:08] [error] handle_connect error: Underlying Transport Error May 23 22:26:09 wc winbindd[838]: [2025/05/23 22:26:09.189440, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 23 22:26:09 wc winbindd[838]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 23 22:26:09 wc winbindd[838]: [2025/05/23 22:26:09.310166, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 23 22:26:09 wc systemd[1]: Started Samba Winbind Daemon. May 23 22:26:09 wc winbindd[838]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 23 22:26:09 wc systemd[1]: Starting Samba SMB Daemon... May 23 22:26:14 wc systemd[1]: apt-daily.service: Succeeded. May 23 22:26:14 wc smbd[858]: [2025/05/23 22:26:14.691335, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 23 22:26:14 wc smbd[858]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 23 22:26:14 wc systemd[1]: Started Daily apt download activities. May 23 22:26:14 wc systemd[1]: Started Samba SMB Daemon. May 23 22:26:14 wc systemd[1]: Reached target Multi-User System. May 23 22:26:14 wc systemd[1]: Reached target Graphical Interface. May 23 22:26:14 wc systemd[1]: Starting Update UTMP about System Runlevel Changes... May 23 22:26:14 wc systemd[1]: Starting Daily apt upgrade and clean activities... May 23 22:26:15 wc systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 23 22:26:15 wc systemd[1]: Started Update UTMP about System Runlevel Changes. May 23 22:26:16 wc systemd[1]: systemd-hostnamed.service: Succeeded. May 23 22:26:18 wc volumio-remote-updater[366]: [2025-05-23 22:26:18] [connect] Successful connection May 23 22:26:19 wc systemd[1]: apt-daily-upgrade.service: Succeeded. May 23 22:26:19 wc systemd[1]: Started Daily apt upgrade and clean activities. May 23 22:26:19 wc systemd[1]: Startup finished in 21.406s (kernel) + 1min 20.008s (userspace) = 1min 41.414s. May 23 22:26:20 wc volumio[815]: info: ------------------------------------------- May 23 22:26:20 wc volumio[815]: info: ----- Volumio3 ---- May 23 22:26:20 wc volumio[815]: info: ------------------------------------------- May 23 22:26:20 wc volumio[815]: info: ----- System startup ---- May 23 22:26:20 wc volumio[815]: info: ------------------------------------------- May 23 22:26:24 wc volumio[815]: info: MYVOLUMIO Environment detected May 23 22:26:25 wc volumio[815]: info: Plugin folders cleanup May 23 22:26:25 wc volumio[815]: info: Scanning into folder /volumio/app/plugins/ May 23 22:26:25 wc volumio[815]: info: Scanning category audio_interface May 23 22:26:25 wc volumio[815]: info: Scanning category miscellanea May 23 22:26:25 wc volumio[815]: info: Scanning category music_service May 23 22:26:25 wc volumio[815]: info: Scanning category plugins.json May 23 22:26:25 wc volumio[815]: info: Scanning category system_controller May 23 22:26:25 wc volumio[815]: info: Scanning category user_interface May 23 22:26:25 wc volumio[815]: info: Scanning into folder /data/plugins/ May 23 22:26:25 wc volumio[815]: info: Scanning category music_service May 23 22:26:25 wc volumio[815]: info: Plugin folders cleanup completed May 23 22:26:25 wc volumio[815]: info: ------------------------------------------- May 23 22:26:25 wc volumio[815]: info: ----- Core plugins startup ---- May 23 22:26:25 wc volumio[815]: info: ------------------------------------------- May 23 22:26:25 wc volumio[815]: info: Loading plugins from folder /volumio/app/plugins/ May 23 22:26:25 wc volumio[815]: info: Adding plugin upnp to MyMusic Plugins May 23 22:26:25 wc volumio[815]: info: Adding plugin airplay_emulation to MyMusic Plugins May 23 22:26:25 wc volumio[815]: info: Adding plugin upnp_browser to MyMusic Plugins May 23 22:26:25 wc volumio[815]: info: Loading plugins from folder /data/plugins/ May 23 22:26:25 wc volumio[815]: info: Loading plugin "system"... May 23 22:26:25 wc volumio[815]: info: Loading plugin "appearance"... May 23 22:26:30 wc volumio[815]: info: Loading plugin "network"... May 23 22:26:31 wc volumio[815]: info: Refreshing Cached IP Addresses May 23 22:26:31 wc volumio[815]: info: Loading plugin "services"... May 23 22:26:31 wc volumio[815]: info: Loading plugin "alsa_controller"... May 23 22:26:31 wc sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 22:26:31 wc sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:31 wc sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 22:26:31 wc sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:31 wc sudo[1007]: pam_unix(sudo:session): session closed for user root May 23 22:26:31 wc sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 23 22:26:31 wc sudo[1005]: pam_unix(sudo:session): session closed for user root May 23 22:26:31 wc sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:32 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 22:26:32 wc volumio[815]: info: Loading plugin "wizard"... May 23 22:26:32 wc volumio[815]: info: Loading plugin "networkfs"... May 23 22:26:32 wc volumio[815]: info: Starting Udev Watcher for removable devices May 23 22:26:32 wc sudo[1033]: 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 May 23 22:26:32 wc sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:32 wc volumio[815]: info: Ignoring mount for partition: boot May 23 22:26:32 wc volumio[815]: info: Ignoring mount for partition: volumio May 23 22:26:32 wc volumio[815]: info: Ignoring mount for partition: volumio_data May 23 22:26:32 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 23 22:26:32 wc volumio[815]: info: Loading plugin "volumio_command_line_client"... May 23 22:26:32 wc sudo[1010]: pam_unix(sudo:session): session closed for user root May 23 22:26:32 wc volumio[815]: info: Plugin upnp is not enabled May 23 22:26:32 wc volumio[815]: info: Loading plugin "my_music"... May 23 22:26:32 wc volumio[815]: info: Loading plugin "mpd"... May 23 22:26:33 wc kernel: Key type cifs.spnego registered May 23 22:26:33 wc kernel: Key type cifs.idmap registered May 23 22:26:33 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. May 23 22:26:33 wc kernel: CIFS: Attempting to mount //192.168.0.242/SAMBA May 23 22:26:33 wc volumio-remote-updater[366]: [2025-05-23 22:26:33] [connect] Successful connection May 23 22:26:34 wc sudo[1033]: pam_unix(sudo:session): session closed for user root May 23 22:26:35 wc volumio[815]: info: Plugin upnp_browser is not enabled May 23 22:26:35 wc volumio[815]: info: Loading plugin "alarm-clock"... May 23 22:26:36 wc volumio[815]: info: Plugin airplay_emulation is not enabled May 23 22:26:36 wc volumio[815]: info: Loading plugin "last_100"... May 23 22:26:36 wc volumio[815]: info: Loading plugin "webradio"... May 23 22:26:37 wc systemd[1]: systemd-timedated.service: Succeeded. May 23 22:26:37 wc volumio[815]: info: Loading plugin "i2s_dacs"... May 23 22:26:37 wc volumio[815]: info: Loading plugin "volumiodiscovery"... May 23 22:26:37 wc volumio[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 23 22:26:37 wc volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi! May 23 22:26:37 wc volumio[815]: *** WARNING *** For more information see May 23 22:26:37 wc volumio[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 23 22:26:37 wc volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi! May 23 22:26:37 wc volumio[815]: *** WARNING *** For more information see May 23 22:26:37 wc node[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 23 22:26:37 wc node[815]: *** WARNING *** Please fix your application to use the native API of Avahi! May 23 22:26:37 wc node[815]: *** WARNING *** For more information see May 23 22:26:37 wc node[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 23 22:26:37 wc node[815]: *** WARNING *** Please fix your application to use the native API of Avahi! May 23 22:26:37 wc node[815]: *** WARNING *** For more information see May 23 22:26:37 wc volumio[815]: info: Applying required configuration parameters for plugin volumiodiscovery May 23 22:26:37 wc volumio[815]: info: Discovery: Started advertising with name: WC May 23 22:26:37 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 23 22:26:37 wc volumio[815]: info: Plugin spop is not enabled May 23 22:26:37 wc volumio[815]: info: Loading plugin "outputs"... May 23 22:26:37 wc volumio[815]: info: Loading plugin "albumart"... May 23 22:26:37 wc volumio[815]: info: Plugin example_plugin is not enabled May 23 22:26:37 wc volumio[815]: info: Loading plugin "inputs"... May 23 22:26:37 wc volumio[815]: info: Loading plugin "updater_comm"... May 23 22:26:40 wc volumio[815]: info: Plugin mpdemulation is not enabled May 23 22:26:40 wc volumio[815]: info: Loading plugin "rest_api"... May 23 22:26:40 wc volumio[815]: info: Loading plugin "websocket"... May 23 22:26:40 wc volumio[815]: info: Starting Socket.io Server version 2.3.0 May 23 22:26:40 wc volumio[815]: info: Loading i18n strings for locale de May 23 22:26:40 wc volumio[815]: Updating browse sources language May 23 22:26:40 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 22:26:41 wc volumio[815]: Forking 1 albumart workers May 23 22:26:41 wc ntpd[537]: Soliciting pool server 213.239.234.28 May 23 22:26:42 wc ntpd[537]: Soliciting pool server 192.248.187.154 May 23 22:26:42 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 22:26:42 wc volumio[815]: info: CoreCommandRouter::initPlayerControls May 23 22:26:42 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 22:26:42 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 22:26:43 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 22:26:43 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 22:26:43 wc volumio[815]: Express server listening on port 3000 May 23 22:26:43 wc volumio[815]: [Metrics] WebUI: 29s 593.41ms May 23 22:26:43 wc volumio[815]: info: CoreStateMachine::resetVolumioState May 23 22:26:43 wc volumio[815]: info: CoreStateMachine::getcurrentVolume May 23 22:26:43 wc volumio[815]: info: CoreCommandRouter::volumioRetrievevolume May 23 22:26:43 wc ntpd[537]: Soliciting pool server 188.245.32.133 May 23 22:26:43 wc ntpd[537]: Soliciting pool server 148.251.235.164 May 23 22:26:43 wc ntpd[537]: Soliciting pool server 159.69.64.189 May 23 22:26:43 wc volumio[815]: info: Volumio Network Manager: Network status updated: 2 May 23 22:26:44 wc volumio[815]: 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 May 23 22:26:44 wc ntpd[537]: Soliciting pool server 131.188.3.222 May 23 22:26:44 wc ntpd[537]: Soliciting pool server 173.249.58.145 May 23 22:26:44 wc ntpd[537]: Soliciting pool server 49.13.223.236 May 23 22:26:44 wc volumio[815]: 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: 2 May 23 22:26:45 wc volumio[815]: 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: 3 May 23 22:26:45 wc volumio[815]: 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: 4 May 23 22:26:45 wc volumio[815]: 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: 5 May 23 22:26:45 wc volumio[815]: 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 May 23 22:26:45 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:45 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:45 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:26:45 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:45 wc volumio[815]: info: CoreStateMachine::updateTrackBlock May 23 22:26:45 wc volumio[815]: info: CorePlayQueue::getTrackBlock May 23 22:26:45 wc volumio[815]: info: CoreCommandRouter::volumioRetrievevolume May 23 22:26:45 wc ntpd[537]: Soliciting pool server 5.45.97.204 May 23 22:26:45 wc ntpd[537]: Soliciting pool server 167.235.70.245 May 23 22:26:45 wc ntpd[537]: Soliciting pool server 81.3.27.46 May 23 22:26:46 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:46 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:46 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:46 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:46 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:46 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:46 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 22:26:46 wc volumio[815]: info: Cannot read play queue from file May 23 22:26:46 wc volumio[815]: 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 May 23 22:26:46 wc ntpd[537]: Soliciting pool server 116.202.118.202 May 23 22:26:46 wc ntpd[537]: Soliciting pool server 51.75.67.47 May 23 22:26:46 wc volumio[815]: 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: 8 May 23 22:26:46 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:46 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:46 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:46 wc volumio[815]: info: CoreStateMachine::setRepeat null single undefined May 23 22:26:46 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:47 wc volumio[815]: info: CoreStateMachine::setRandom true May 23 22:26:47 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:47 wc volumio[815]: info: Setting Device type: Raspberry PI May 23 22:26:47 wc volumio[815]: info: May 23 22:26:47 wc volumio[815]: ---------------------------- Client requests Volume 50 May 23 22:26:47 wc volumio[815]: info: VolumeController::SetAlsaVolume50 May 23 22:26:47 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:47 wc volumio[815]: info: May 23 22:26:47 wc volumio[815]: ---------------------------- Client requests Volume 50 May 23 22:26:47 wc volumio[815]: info: VolumeController::SetAlsaVolume50 May 23 22:26:47 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:47 wc volumio[815]: info: May 23 22:26:47 wc volumio[815]: ---------------------------- Client requests Volume 50 May 23 22:26:47 wc volumio[815]: info: VolumeController::SetAlsaVolume50 May 23 22:26:47 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:47 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:47 wc ntpd[537]: Soliciting pool server 2a01:4f8:120:710b::123 May 23 22:26:47 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:48 wc volumio[815]: info: Completed loading Core Plugins May 23 22:26:48 wc volumio[815]: info: Preparing to generate the ALSA configuration file May 23 22:26:48 wc volumio[815]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 23 22:26:48 wc volumio[815]: info: Reading ALSA contributions from plugins. May 23 22:26:48 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 23 22:26:48 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 23 22:26:48 wc volumio[815]: info: May 23 22:26:48 wc volumio[815]: ---------------------------- Client requests Random true May 23 22:26:48 wc volumio[815]: info: CoreCommandRouter::volumioRandom May 23 22:26:48 wc volumio[815]: info: CoreCommandRouter::writePlayerControls May 23 22:26:48 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:48 wc volumio[815]: info: CoreStateMachine::setRandom true May 23 22:26:48 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:48 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:48 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:48 wc volumio[815]: info: May 23 22:26:48 wc volumio[815]: ---------------------------- Client requests Random true May 23 22:26:49 wc volumio-remote-updater[366]: [2025-05-23 22:26:48] [connect] Successful connection May 23 22:26:49 wc volumio[815]: Starting albumart workers May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioRandom May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::writePlayerControls May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: CoreStateMachine::setRandom true May 23 22:26:49 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:49 wc volumio[815]: info: May 23 22:26:49 wc volumio[815]: ---------------------------- Client requests Random true May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioRandom May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::writePlayerControls May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: CoreStateMachine::setRandom true May 23 22:26:49 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:49 wc volumio[815]: info: May 23 22:26:49 wc volumio[815]: ---------------------------- Client requests Volume 50 May 23 22:26:49 wc volumio[815]: info: VolumeController::SetAlsaVolume50 May 23 22:26:49 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:49 wc volumio[815]: info: Discovery: adding 4eafa67a-dc6e-4837-9947-5761bc470cb8 May 23 22:26:49 wc volumio[815]: info: Discovery: Found device WC May 23 22:26:49 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:49 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:49 wc volumio[815]: info: Discovery: adding 06459c18-b434-48b2-8377-6ce0327572a0 May 23 22:26:49 wc volumio[815]: info: Discovery: Found device Tivoli Audio May 23 22:26:49 wc volumio[815]: info: Discovery: Connecting to remote: 192.168.0.99 May 23 22:26:50 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:50 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:50 wc volumio[815]: info: CoreCommandRouter::volumioGetState May 23 22:26:50 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:50 wc volumio-remote-updater[366]: [2025-05-23 22:26:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748032008 101 May 23 22:26:50 wc volumio[815]: 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: 9 May 23 22:26:50 wc volumio[815]: info: May 23 22:26:50 wc volumio[815]: ---------------------------- Client requests Volumio Play Playlist Lullaby May 23 22:26:50 wc volumio[815]: info: Play playlist Lullaby May 23 22:26:50 wc volumio[815]: info: May 23 22:26:50 wc volumio[815]: ---------------------------- Client requests Volumio Play Playlist Lullaby May 23 22:26:50 wc volumio[815]: info: Play playlist Lullaby May 23 22:26:50 wc volumio[815]: info: May 23 22:26:50 wc volumio[815]: ---------------------------- Client requests Volumio Play Playlist Lullaby May 23 22:26:50 wc volumio[815]: info: Play playlist Lullaby May 23 22:26:50 wc volumio[815]: info: May 23 22:26:50 wc volumio[815]: ---------------------------- Client requests Random true May 23 22:26:50 wc volumio[815]: info: CoreCommandRouter::volumioRandom May 23 22:26:50 wc volumio[815]: info: CoreCommandRouter::writePlayerControls May 23 22:26:50 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:50 wc volumio[815]: info: CoreStateMachine::setRandom true May 23 22:26:50 wc volumio[815]: info: CoreStateMachine::pushState May 23 22:26:50 wc volumio[815]: info: CorePlayQueue::getTrack 0 May 23 22:26:50 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:50 wc volumio[815]: info: May 23 22:26:50 wc volumio[815]: ---------------------------- Client requests Volumio Play Playlist Lullaby May 23 22:26:50 wc volumio[815]: info: Play playlist Lullaby May 23 22:26:51 wc volumio[815]: info: Discovery: Connected to remote: 192.168.0.99 May 23 22:26:51 wc volumio[815]: info: Discovery: adding 6a4f79cd-e46c-4295-a6ea-3358ca887812 May 23 22:26:51 wc volumio[815]: info: Discovery: Found device Schlafzimmer May 23 22:26:51 wc volumio[815]: info: Discovery: Connecting to remote: 192.168.0.102 May 23 22:26:51 wc volumio[815]: info: Asound.conf file unchanged, so no further update is needed May 23 22:26:51 wc volumio[815]: info: Output device has changed, restarting MPD May 23 22:26:51 wc volumio[815]: info: ___________ START PLUGINS ___________ May 23 22:26:51 wc volumio[815]: info: ControllerMpd::onStart: Initializing MPD May 23 22:26:54 wc ntpd[537]: receive: Unexpected origin timestamp 0xebdb588b.d7cd66e1 does not match aorg 0000000000.00000000 from server@167.235.70.245 xmt 0xebdb588e.235fffe6 May 23 22:26:54 wc ntpd[537]: receive: Unexpected origin timestamp 0xebdb588b.d7d3ee38 does not match aorg 0000000000.00000000 from server@49.13.223.236 xmt 0xebdb588e.239cdc14 May 23 22:26:54 wc ntpd[537]: receive: Unexpected origin timestamp 0xebdb588b.d7d9ae0c does not match aorg 0000000000.00000000 from server@131.188.3.222 xmt 0xebdb588e.242bc6c9 May 23 22:26:54 wc ntpd[537]: receive: Unexpected origin timestamp 0xebdb588b.d7be67a2 does not match aorg 0000000000.00000000 from server@51.75.67.47 xmt 0xebdb588e.2500bd08 May 23 22:26:54 wc sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 22:26:54 wc sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:54 wc volumio[815]: info: Creating MPD Configuration file May 23 22:26:54 wc sudo[1132]: pam_unix(sudo:session): session closed for user root May 23 22:26:54 wc sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 22:26:54 wc sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:54 wc volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 23 22:26:54 wc systemd[1]: Stopping Music Player Daemon... May 23 22:26:54 wc volumio[815]: info: [1748032014651] CoreMusicLibrary::Adding element Last_100 May 23 22:26:54 wc systemd[1]: mpd.service: Succeeded. May 23 22:26:54 wc systemd[1]: Stopped Music Player Daemon. May 23 22:26:54 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 22:26:54 wc systemd[1]: Starting Music Player Daemon... May 23 22:26:55 wc sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 22:26:55 wc sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:55 wc volumio[815]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 23 22:26:55 wc volumio[815]: info: [1748032015228] CoreMusicLibrary::Adding element Webradio May 23 22:26:55 wc sudo[1137]: pam_unix(sudo:session): session closed for user root May 23 22:26:55 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 22:26:55 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 22:26:55 wc sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 22:26:55 wc sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:55 wc sudo[1145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 22:26:55 wc sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:55 wc volumio[815]: info: Initializing BBC Radios May 23 22:26:55 wc sudo[1145]: pam_unix(sudo:session): session closed for user root May 23 22:26:56 wc systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 23 22:26:56 wc systemd[1]: mpd.service: Succeeded. May 23 22:26:56 wc systemd[1]: Stopped Music Player Daemon. May 23 22:26:56 wc systemd[1]: Starting Music Player Daemon... May 23 22:26:56 wc volumio[815]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 23 22:26:56 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 22:26:56 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 22:26:56 wc volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 22:26:56 wc sudo[1162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 22:26:56 wc volumio[815]: info: Volumio Calling Home May 23 22:26:56 wc sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 22:26:56 wc sudo[1162]: pam_unix(sudo:session): session closed for user root May 23 22:26:59 wc volumio[815]: info: CoreCommandRouter::volumioClearQueue May 23 22:26:59 wc volumio[815]: info: CoreStateMachine::ClearQueue May 23 22:26:59 wc volumio[815]: info: CoreStateMachine::stop May 23 22:26:59 wc volumio[815]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:26:59 wc volumio[815]: info: CorePlayQueue::clearPlayQueue May 23 22:26:59 wc volumio[815]: info: CorePlayQueue::saveQueue May 23 22:26:59 wc volumio[815]: info: CoreCommandRouter::volumioPushState May 23 22:26:59 wc volumio[815]: info: CoreCommandRouter::volumioPushQueue May 23 22:26:59 wc volumio[815]: info: CoreCommandRouter::volumioAddQueueItems May 23 22:26:59 wc volumio[815]: info: CoreStateMachine::addQueueItems May 23 22:26:59 wc volumio[815]: info: CorePlayQueue::addQueueItems May 23 22:26:59 wc volumio[815]: info: Preload queue cleared May 23 22:26:59 wc volumio[815]: info: Adding Item to queue: mnt/NAS/SAMBA/Lullaby/Hey Jude - Lullaby Renditions of The Beatles - Rockabye Baby!.mp3 May 23 22:26:59 wc volumio[815]: info: Exploding uri mnt/NAS/SAMBA/Lullaby/Hey Jude - Lullaby Renditions of The Beatles - Rockabye Baby!.mp3 in service mpd May 23 22:26:59 wc volumio[815]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 22:26:59 wc volumio[815]: TypeError: Cannot read property 'then' of undefined May 23 22:26:59 wc volumio[815]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) May 23 22:26:59 wc volumio[815]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) May 23 22:26:59 wc volumio[815]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) May 23 22:26:59 wc volumio[815]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 23 22:26:59 wc volumio[815]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 23 22:26:59 wc volumio[815]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 23 22:26:59 wc volumio[815]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 23 22:26:59 wc volumio[815]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) May 23 22:26:59 wc volumio[815]: at /volumio/app/playlistManager.js:726:30 May 23 22:26:59 wc volumio[815]: at /volumio/node_modules/jsonfile/index.js:46:5 May 23 22:26:59 wc volumio[815]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 22:27:04 wc sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 22:26 May 23 22:27:04 wc sudo[1195]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"