-- Logs begin at Fri 2024-11-08 18:57:21 CET, end at Sun 2025-05-04 14:44:29 CEST. -- May 04 14:44:10 volumio systemd-timedated[981]: Changed local time to Sun May 4 14:44:10 2025 May 04 14:44:10 volumio sudo[973]: pam_unix(sudo:session): session closed for user root May 04 14:44:10 volumio volumio-time-update[608]: volumio-time-update-util: System time updated successfully. May 04 14:44:10 volumio systemd[1]: Starting Daily apt download activities... May 04 14:44:10 volumio systemd[1]: Started Volumio Time Update Utility. May 04 14:44:10 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 04 14:44:10 volumio ntpd[785]: Listen normally on 3 wlan0 192.168.178.49:123 May 04 14:44:10 volumio ntpd[785]: new interface(s) found: waking up resolver May 04 14:44:11 volumio systemd[1]: apt-daily.service: Succeeded. May 04 14:44:11 volumio systemd[1]: Started Daily apt download activities. May 04 14:44:11 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 04 14:44:12 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 04 14:44:12 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 04 14:44:12 volumio nmbd[735]: [2025/05/04 14:44:12.394199, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 14:44:12 volumio systemd[1]: Started Samba NMB Daemon. May 04 14:44:12 volumio nmbd[735]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 04 14:44:12 volumio nmbd[735]: [2025/05/04 14:44:12.416416, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 04 14:44:12 volumio nmbd[735]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.49 for name WORKGROUP<1d>. May 04 14:44:12 volumio nmbd[735]: This response was from IP 192.168.178.110, reporting an IP address of 192.168.178.110. May 04 14:44:12 volumio systemd[1]: Starting Samba Winbind Daemon... May 04 14:44:12 volumio winbindd[1104]: [2025/05/04 14:44:12.862930, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 04 14:44:12 volumio winbindd[1104]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 04 14:44:13 volumio winbindd[1104]: [2025/05/04 14:44:13.032474, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 14:44:13 volumio systemd[1]: Started Samba Winbind Daemon. May 04 14:44:13 volumio winbindd[1104]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 04 14:44:13 volumio systemd[1]: Starting Samba SMB Daemon... May 04 14:44:13 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:13 volumio volumio[985]: info: ----- Volumio3 ---- May 04 14:44:13 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:13 volumio volumio[985]: info: ----- System startup ---- May 04 14:44:13 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:13 volumio smbd[1117]: [2025/05/04 14:44:13.862708, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 04 14:44:13 volumio systemd[1]: Started Samba SMB Daemon. May 04 14:44:13 volumio smbd[1117]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 04 14:44:13 volumio systemd[1]: Reached target Multi-User System. May 04 14:44:13 volumio systemd[1]: Reached target Graphical Interface. May 04 14:44:13 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 04 14:44:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 04 14:44:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 04 14:44:13 volumio systemd[1]: Startup finished in 22.540s (kernel) + 29.458s (userspace) = 51.999s. May 04 14:44:16 volumio wpa_supplicant[883]: wlan0: Associated with 7c:ff:4d:dd:86:e6 May 04 14:44:16 volumio dhcpcd[900]: wlan0: carrier lost May 04 14:44:16 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7c:ff:4d:dd:86:e6 completed [id=0 id_str=] May 04 14:44:16 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 04 14:44:16 volumio avahi-daemon[634]: Withdrawing address record for 192.168.178.49 on wlan0. May 04 14:44:16 volumio avahi-daemon[634]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.49. May 04 14:44:16 volumio dhcpcd[900]: wlan0: deleting route to 192.168.178.0/24 May 04 14:44:16 volumio dhcpcd[900]: wlan0: deleting default route via 192.168.178.1 May 04 14:44:16 volumio avahi-daemon[634]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 14:44:16 volumio dhcpcd[900]: wlan0: carrier acquired May 04 14:44:16 volumio dhcpcd[900]: wlan0: IAID eb:cd:46:97 May 04 14:44:17 volumio volumio-remote-updater[635]: [2025-05-04 14:44:17] [connect] Successful connection May 04 14:44:17 volumio volumio[985]: info: MYVOLUMIO Environment detected May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/airplay_emulation. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/inputs. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/mpd. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/webradio. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/example_plugin. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/last_100. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin music_service/upnp_browser. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin audio_interface/outputs. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin audio_interface/upnp. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin audio_interface/alsa_controller. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin miscellanea/alarm-clock. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin miscellanea/albumart. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin miscellanea/appearance. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin miscellanea/wizard. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin miscellanea/my_music. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin user_interface/websocket. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin user_interface/mpdemulation. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin user_interface/rest_api. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/updater_comm. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/network. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/networkfs. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/services. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/system. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/i2s_dacs. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/volumiodiscovery. Adding it May 04 14:44:17 volumio volumio[985]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it May 04 14:44:17 volumio volumio[985]: info: Plugin folders cleanup May 04 14:44:17 volumio volumio[985]: info: Scanning into folder /volumio/app/plugins/ May 04 14:44:17 volumio volumio[985]: info: Scanning category audio_interface May 04 14:44:17 volumio volumio[985]: info: Scanning category miscellanea May 04 14:44:17 volumio volumio[985]: info: Scanning category music_service May 04 14:44:17 volumio volumio[985]: info: Scanning category plugins.json May 04 14:44:17 volumio volumio[985]: info: Scanning category system_controller May 04 14:44:17 volumio volumio[985]: info: Scanning category user_interface May 04 14:44:17 volumio volumio[985]: info: Scanning into folder /data/plugins/ May 04 14:44:17 volumio volumio[985]: info: Plugin folders cleanup completed May 04 14:44:17 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:17 volumio volumio[985]: info: ----- Core plugins startup ---- May 04 14:44:17 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:17 volumio volumio[985]: info: Loading plugins from folder /volumio/app/plugins/ May 04 14:44:17 volumio volumio[985]: info: Adding plugin upnp to MyMusic Plugins May 04 14:44:17 volumio volumio[985]: info: Adding plugin airplay_emulation to MyMusic Plugins May 04 14:44:17 volumio volumio[985]: info: Adding plugin upnp_browser to MyMusic Plugins May 04 14:44:17 volumio volumio[985]: info: Loading plugins from folder /data/plugins/ May 04 14:44:17 volumio volumio[985]: info: Loading plugin "system"... May 04 14:44:17 volumio volumio[985]: info: No id defined. Creating one May 04 14:44:17 volumio volumio[985]: info: Loading plugin "appearance"... May 04 14:44:17 volumio dhcpcd[900]: wlan0: soliciting a DHCP lease May 04 14:44:17 volumio dhcpcd[900]: wlan0: offered 192.168.178.49 from 192.168.178.1 May 04 14:44:17 volumio dhcpcd[900]: wlan0: probing address 192.168.178.49/24 May 04 14:44:17 volumio dhcpcd[900]: wlan0: soliciting an IPv6 router May 04 14:44:17 volumio ntpd[785]: Deleting interface #3 wlan0, 192.168.178.49#123, interface stats: received=0, sent=0, dropped=0, active_time=7 secs May 04 14:44:18 volumio volumio[985]: info: Loading plugin "network"... May 04 14:44:18 volumio volumio[985]: info: Refreshing Cached IP Addresses May 04 14:44:19 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 14:44:19 volumio sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 14:44:19 volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:19 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:19 volumio sudo[1166]: pam_unix(sudo:session): session closed for user root May 04 14:44:19 volumio volumio[985]: info: Loading plugin "services"... May 04 14:44:19 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root May 04 14:44:19 volumio volumio[985]: info: Loading plugin "alsa_controller"... May 04 14:44:19 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 04 14:44:19 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:19 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 14:44:19 volumio volumio[985]: info: Loading plugin "wizard"... May 04 14:44:19 volumio volumio[985]: info: Loading plugin "networkfs"... May 04 14:44:19 volumio volumio[985]: info: Starting Udev Watcher for removable devices May 04 14:44:19 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=audio,password=audio4ce,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.27/audio /mnt/NAS/Andromeda May 04 14:44:19 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:19 volumio volumio[985]: info: Ignoring mount for partition: boot May 04 14:44:19 volumio volumio[985]: info: Ignoring mount for partition: volumio May 04 14:44:19 volumio volumio[985]: info: Ignoring mount for partition: volumio_data May 04 14:44:19 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 14:44:19 volumio volumio[985]: info: Loading plugin "volumio_command_line_client"... May 04 14:44:19 volumio volumio[985]: info: Loading plugin "upnp"... May 04 14:44:19 volumio volumio[985]: info: [1746362659287] Starting Upmpd Daemon May 04 14:44:19 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 14:44:19 volumio volumio[985]: info: Loading plugin "my_music"... May 04 14:44:19 volumio volumio[985]: info: Loading plugin "mpd"... May 04 14:44:19 volumio kernel: Key type cifs.spnego registered May 04 14:44:19 volumio kernel: Key type cifs.idmap registered May 04 14:44:19 volumio 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 04 14:44:19 volumio kernel: CIFS: Attempting to mount //192.168.178.27/audio May 04 14:44:19 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 04 14:44:19 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 04 14:44:19 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root May 04 14:44:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 04 14:44:19 volumio volumio[985]: info: Loading plugin "upnp_browser"... May 04 14:44:19 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root May 04 14:44:20 volumio volumio[985]: info: Loading plugin "alarm-clock"... May 04 14:44:20 volumio volumio[985]: info: Loading plugin "airplay_emulation"... May 04 14:44:20 volumio volumio[985]: info: Starting Shairport Sync May 04 14:44:20 volumio volumio[985]: info: Loading plugin "last_100"... May 04 14:44:20 volumio volumio[985]: info: Loading plugin "webradio"... May 04 14:44:20 volumio volumio[985]: info: Loading plugin "i2s_dacs"... May 04 14:44:20 volumio volumio[985]: info: I2S DAC not set, start Auto-detection May 04 14:44:20 volumio volumio[985]: info: Loading plugin "volumiodiscovery"... May 04 14:44:20 volumio volumio[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 14:44:20 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 14:44:20 volumio volumio[985]: *** WARNING *** For more information see May 04 14:44:20 volumio volumio[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 14:44:20 volumio node[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 14:44:20 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 14:44:20 volumio volumio[985]: *** WARNING *** For more information see May 04 14:44:20 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 14:44:20 volumio node[985]: *** WARNING *** For more information see May 04 14:44:20 volumio node[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 14:44:20 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 14:44:20 volumio node[985]: *** WARNING *** For more information see May 04 14:44:20 volumio volumio[985]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 14:44:20 volumio volumio[985]: info: Discovery: Started advertising with name: undefined May 04 14:44:20 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 14:44:20 volumio volumio[985]: info: Loading plugin "outputs"... May 04 14:44:20 volumio volumio[985]: info: Loading plugin "albumart"... May 04 14:44:20 volumio volumio[985]: info: Plugin example_plugin is not enabled May 04 14:44:20 volumio volumio[985]: info: Loading plugin "inputs"... May 04 14:44:20 volumio volumio[985]: info: Loading plugin "updater_comm"... May 04 14:44:21 volumio volumio[985]: info: Plugin mpdemulation is not enabled May 04 14:44:21 volumio volumio[985]: info: Loading plugin "rest_api"... May 04 14:44:21 volumio volumio[985]: info: Loading plugin "websocket"... May 04 14:44:21 volumio volumio[985]: info: Starting Socket.io Server version 2.3.0 May 04 14:44:21 volumio volumio[985]: info: Loading i18n strings for locale de May 04 14:44:21 volumio volumio[985]: Updating browse sources language May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:44:21 volumio volumio[985]: Forking 3 albumart workers May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::initPlayerControls May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:44:21 volumio volumio[985]: Express server listening on port 3000 May 04 14:44:21 volumio volumio[985]: [Metrics] WebUI: 9s 805.94ms May 04 14:44:21 volumio volumio[985]: info: CoreStateMachine::resetVolumioState May 04 14:44:21 volumio volumio[985]: info: CoreStateMachine::getcurrentVolume May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::volumioRetrievevolume May 04 14:44:21 volumio volumio[985]: info: CoreStateMachine::pushState May 04 14:44:21 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::volumioPushState May 04 14:44:21 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:22 volumio volumio[985]: Starting albumart workers May 04 14:44:22 volumio volumio[985]: Starting albumart workers May 04 14:44:22 volumio volumio[985]: Starting albumart workers May 04 14:44:22 volumio volumio[985]: info: Volumio Network Manager: Network status updated: 0 May 04 14:44:22 volumio volumio[985]: info: Cannot mount NAS Andromeda at system boot, trial number 1 ,retrying in 5 seconds May 04 14:44:22 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 May 04 14:44:22 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 May 04 14:44:22 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 May 04 14:44:22 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 14:44:22 volumio volumio[985]: info: Discovery: Getting this device information May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:22 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 14:44:22 volumio volumio[985]: info: Reloading queue from file May 04 14:44:22 volumio volumio[985]: info: Setting Device type: Raspberry PI May 04 14:44:22 volumio volumio[985]: info: Completed loading Core Plugins May 04 14:44:22 volumio volumio[985]: info: Preparing to generate the ALSA configuration file May 04 14:44:22 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:22 volumio volumio[985]: info: Asound.conf file unchanged, so no further update is needed May 04 14:44:22 volumio volumio[985]: info: Output device has changed, restarting MPD May 04 14:44:22 volumio volumio[985]: info: Output device has changed, restarting Shairport Sync May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:22 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:22 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 14:44:22 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:22 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 14:44:22 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:22 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root May 04 14:44:22 volumio systemd[1]: Stopping Music Player Daemon... May 04 14:44:22 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 14:44:23 volumio volumio[985]: info: ___________ START PLUGINS ___________ May 04 14:44:23 volumio volumio[985]: info: ControllerMpd::onStart: Initializing MPD May 04 14:44:23 volumio volumio[985]: info: Creating MPD Configuration file May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:44:23 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 14:44:23 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 14:44:23 volumio volumio[985]: info: [1746362663116] CoreMusicLibrary::Adding element Medienserver May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:44:23 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root May 04 14:44:23 volumio dhcpcd[900]: wlan0: leased 192.168.178.49 for 864000 seconds May 04 14:44:23 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 14:44:23 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:23 volumio avahi-daemon[634]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.49. May 04 14:44:23 volumio systemd[1]: mpd.service: Succeeded. May 04 14:44:23 volumio systemd[1]: Stopped Music Player Daemon. May 04 14:44:23 volumio dhcpcd[900]: wlan0: adding route to 192.168.178.0/24 May 04 14:44:23 volumio dhcpcd[900]: wlan0: adding default route via 192.168.178.1 May 04 14:44:23 volumio avahi-daemon[634]: New relevant interface wlan0.IPv4 for mDNS. May 04 14:44:23 volumio avahi-daemon[634]: Registering new address record for 192.168.178.49 on wlan0.IPv4. May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:23 volumio systemd[1]: Starting Music Player Daemon... May 04 14:44:23 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 04 14:44:23 volumio systemd[1]: mpd.service: Succeeded. May 04 14:44:23 volumio systemd[1]: Stopped Music Player Daemon. May 04 14:44:23 volumio systemd[1]: Starting Music Player Daemon... May 04 14:44:23 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:23 volumio sh[511]: timed out May 04 14:44:23 volumio dhcpcd[560]: timed out May 04 14:44:23 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 14:44:23 volumio sh[511]: dhcpcd exited May 04 14:44:23 volumio dhcpcd[560]: dhcpcd exited May 04 14:44:23 volumio sh[511]: ifup: failed to bring up eth0 May 04 14:44:23 volumio volumio[985]: info: [1746362663392] CoreMusicLibrary::Adding element Last_100 May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:44:23 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 04 14:44:23 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 14:44:23 volumio volumio[985]: info: [1746362663408] CoreMusicLibrary::Adding element Webradio May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:44:23 volumio sudo[1292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 04 14:44:23 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 14:44:23 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root May 04 14:44:23 volumio volumio[985]: info: Initializing BBC Radios May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:23 volumio volumio[985]: info: Volumio Calling Home May 04 14:44:23 volumio volumio[985]: info: MPD Permissions set May 04 14:44:23 volumio volumio[985]: info: MPD Permissions set May 04 14:44:23 volumio volumio[985]: error: error May 04 14:44:23 volumio volumio[985]: info: Volumio called home May 04 14:44:23 volumio volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:44:23 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:44:24 volumio volumio[985]: info: Starting Shairport Sync May 04 14:44:24 volumio volumio[985]: info: Starting Shairport Sync May 04 14:44:24 volumio volumio[985]: info: Starting Shairport Sync May 04 14:44:24 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 14:44:24 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:24 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 14:44:24 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:24 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 14:44:24 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 04 14:44:24 volumio systemd[1]: shairport-sync.service: Succeeded. May 04 14:44:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 04 14:44:24 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:24 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:24 volumio volumio[985]: info: Discovery: adding f2a71eaa-255d-4a94-ae9e-45cf1937cbaa May 04 14:44:24 volumio volumio[985]: info: Discovery: Found device undefined May 04 14:44:24 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:24 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 14:44:24 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root May 04 14:44:24 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root May 04 14:44:24 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root May 04 14:44:24 volumio volumio[985]: info: Shairport-Sync Started May 04 14:44:24 volumio volumio[985]: Error adding Membership: Error: addMembership EINVAL May 04 14:44:24 volumio volumio[985]: info: Shairport-Sync Started May 04 14:44:24 volumio volumio[985]: info: Shairport-Sync Started May 04 14:44:24 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 04 14:44:24 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 04 14:44:24 volumio ntpd[785]: Listen normally on 4 wlan0 192.168.178.49:123 May 04 14:44:24 volumio ntpd[785]: new interface(s) found: waking up resolver May 04 14:44:25 volumio mpd[1316]: May 04 14:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 14:44:25 volumio systemd[1]: Started Music Player Daemon. May 04 14:44:25 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root May 04 14:44:25 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root May 04 14:44:25 volumio volumio[985]: info: Completed starting Core Plugins May 04 14:44:25 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:25 volumio volumio[985]: info: ----- MyVolumio plugins startup ---- May 04 14:44:25 volumio volumio[985]: info: ------------------------------------------- May 04 14:44:25 volumio volumio[985]: info: [MyVolumio PluginManager] Fetching plans data.... May 04 14:44:25 volumio volumio[985]: error: MPD error: The expression evaluated to a falsy value: May 04 14:44:25 volumio volumio[985]: assert.ok(self.idling) May 04 14:44:25 volumio volumio[985]: error: The expression evaluated to a falsy value: May 04 14:44:25 volumio volumio[985]: assert.ok(self.idling) May 04 14:44:25 volumio volumio[985]: info: MPD running with PID1316 May 04 14:44:25 volumio volumio[985]: ,establishing connection May 04 14:44:25 volumio volumio[985]: error: MPD error: The expression evaluated to a falsy value: May 04 14:44:25 volumio volumio[985]: assert.ok(self.idling) May 04 14:44:25 volumio volumio[985]: error: The expression evaluated to a falsy value: May 04 14:44:25 volumio volumio[985]: assert.ok(self.idling) May 04 14:44:25 volumio volumio[985]: error: updateQueue error: null May 04 14:44:26 volumio volumio[985]: verbose: New Socket.io Connection to 192.168.178.49 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::Close All Modals sent May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:26 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 14:44:26 volumio volumio[985]: info: Received Get System Info May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 14:44:26 volumio volumio[985]: info: Discovery: Getting this device information May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:26 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 14:44:26 volumio volumio[985]: info: CoreCommandRouter::volumioGetState May 04 14:44:26 volumio volumio[985]: info: CorePlayQueue::getTrack 0 May 04 14:44:26 volumio volumio[985]: info: Listing playlists May 04 14:44:27 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 04 14:44:27 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=audio,password=audio4ce,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.27/audio /mnt/NAS/Andromeda May 04 14:44:27 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:44:27 volumio kernel: CIFS: Attempting to mount //192.168.178.27/audio May 04 14:44:28 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root May 04 14:44:29 volumio volumio[985]: info: Volumio Network Manager: Network status updated: 2 May 04 14:44:29 volumio volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:44:29 volumio volumio[985]: TypeError: Cannot read property 'charAt' of undefined May 04 14:44:29 volumio volumio[985]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) May 04 14:44:29 volumio volumio[985]: at listOnTimeout (internal/timers.js:554:17) May 04 14:44:29 volumio volumio[985]: at processTimers (internal/timers.js:497:7) May 04 14:44:29 volumio volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:44:29 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 14:43 May 04 14:44:29 volumio sudo[1402]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"