-- Logs begin at Mon 2024-10-28 10:10:42 UTC, end at Mon 2024-10-28 10:12:19 UTC. -- Oct 28 10:11:00 volumio wireless.js[516]: trying... Oct 28 10:11:00 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:00 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:00 volumio sudo[796]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: carrier acquired Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: connected to Access Point `laborator' Oct 28 10:11:00 volumio wpa_supplicant[770]: wlan0: Associated with 7c:ff:4d:f3:38:ed Oct 28 10:11:00 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7c:ff:4d:f3:38:ed completed [id=0 id_str=] Oct 28 10:11:00 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 28 10:11:00 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 28 10:11:00 volumio dhcpcd[782]: DUID 00:01:00:01:29:d8:62:95:dc:a6:32:04:52:28 Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: IAID 32:04:52:28 Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: adding address fe80::dea6:32ff:fe04:5228 Oct 28 10:11:00 volumio dhcpcd[782]: ipv6_addaddr1: Permission denied Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: carrier lost Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: carrier acquired Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: IAID 32:04:52:28 Oct 28 10:11:00 volumio dhcpcd[782]: wlan0: soliciting an IPv6 router Oct 28 10:11:01 volumio dhcpcd[782]: wlan0: soliciting a DHCP lease Oct 28 10:11:01 volumio dhcpcd[782]: wlan0: offered 192.168.178.32 from 192.168.178.1 Oct 28 10:11:01 volumio dhcpcd[782]: wlan0: probing address 192.168.178.32/24 Oct 28 10:11:01 volumio wireless.js[516]: trying... Oct 28 10:11:01 volumio sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:01 volumio sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:01 volumio sudo[811]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:01 volumio volumio-remote-updater[520]: [2024-10-28 10:11:01] [info] asio async_connect error: system:111 (Connection refused) Oct 28 10:11:01 volumio volumio-remote-updater[520]: [2024-10-28 10:11:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 28 10:11:01 volumio volumio-remote-updater[520]: [2024-10-28 10:11:01] [error] handle_connect error: Underlying Transport Error Oct 28 10:11:02 volumio wireless.js[516]: trying... Oct 28 10:11:02 volumio sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:02 volumio sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:02 volumio sudo[814]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:03 volumio wireless.js[516]: trying... Oct 28 10:11:03 volumio sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:03 volumio sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:03 volumio sudo[817]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:04 volumio wireless.js[516]: trying... Oct 28 10:11:04 volumio sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:04 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:04 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:05 volumio wireless.js[516]: trying... Oct 28 10:11:05 volumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:05 volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:05 volumio sudo[823]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:06 volumio wireless.js[516]: trying... Oct 28 10:11:06 volumio sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:06 volumio sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:06 volumio sudo[826]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:06 volumio dhcpcd[782]: wlan0: leased 192.168.178.32 for 864000 seconds Oct 28 10:11:06 volumio avahi-daemon[528]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.32. Oct 28 10:11:06 volumio avahi-daemon[528]: New relevant interface wlan0.IPv4 for mDNS. Oct 28 10:11:06 volumio dhcpcd[782]: wlan0: adding route to 192.168.178.0/24 Oct 28 10:11:06 volumio avahi-daemon[528]: Registering new address record for 192.168.178.32 on wlan0.IPv4. Oct 28 10:11:06 volumio dhcpcd[782]: wlan0: adding default route via 192.168.178.1 Oct 28 10:11:06 volumio ntpd[660]: ntpd exiting on signal 15 (Terminated) Oct 28 10:11:06 volumio systemd[1]: Stopping Network Time Service... Oct 28 10:11:06 volumio systemd[1]: ntp.service: Succeeded. Oct 28 10:11:06 volumio systemd[1]: Stopped Network Time Service. Oct 28 10:11:06 volumio systemd[1]: Starting Network Time Service... Oct 28 10:11:06 volumio ntpd[859]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 28 10:11:06 volumio ntpd[859]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 28 10:11:06 volumio systemd[1]: Started Network Time Service. Oct 28 10:11:06 volumio ntpd[866]: proto: precision = 1.037 usec (-20) Oct 28 10:11:06 volumio ntpd[866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 28 10:11:06 volumio ntpd[866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 28 10:11:06 volumio ntpd[866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 123 days ago Oct 28 10:11:06 volumio ntpd[866]: Listen and drop on 0 v6wildcard [::]:123 Oct 28 10:11:06 volumio ntpd[866]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 28 10:11:06 volumio ntpd[866]: Listen normally on 2 lo 127.0.0.1:123 Oct 28 10:11:06 volumio ntpd[866]: Listen normally on 3 wlan0 192.168.178.32:123 Oct 28 10:11:06 volumio ntpd[866]: Listening on routing socket on fd #20 for interface updates Oct 28 10:11:06 volumio ntpd[866]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 28 10:11:06 volumio ntpd[866]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 28 10:11:07 volumio wireless.js[516]: trying... Oct 28 10:11:07 volumio sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 10:11:07 volumio sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:07 volumio sudo[869]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:07 volumio wireless.js[516]: Connected to: ----laborator Oct 28 10:11:07 volumio wireless.js[516]: ---- Oct 28 10:11:07 volumio sudo[872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:11:07 volumio sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:07 volumio sudo[872]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:07 volumio wireless.js[516]: ... joined AP, wlan0 IPv4 is 192.168.178.32, ipV6 is undefined Oct 28 10:11:07 volumio wireless.js[516]: It's done! AP Oct 28 10:11:07 volumio systemd[1]: Started Wireless Services. Oct 28 10:11:07 volumio systemd[1]: Started Volumio Backend Module. Oct 28 10:11:07 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 28 10:11:07 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: Setting RT Priority for mpd Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: pid 35's current scheduling policy: SCHED_OTHER Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: pid 35's current scheduling priority: 0 Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: Setting MPD Affinity Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: pid 3's current affinity mask: f Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 28 10:11:07 volumio volumio-cpu-tweak[878]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 28 10:11:07 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 28 10:11:07 volumio ntpd[866]: Soliciting pool server 213.239.234.28 Oct 28 10:11:08 volumio ntpd[866]: Soliciting pool server 130.61.66.81 Oct 28 10:11:08 volumio ntpd[866]: Soliciting pool server 217.14.146.53 Oct 28 10:11:09 volumio volumio[877]: info: ------------------------------------------- Oct 28 10:11:09 volumio volumio[877]: info: ----- Volumio3 ---- Oct 28 10:11:09 volumio volumio[877]: info: ------------------------------------------- Oct 28 10:11:09 volumio volumio[877]: info: ----- System startup ---- Oct 28 10:11:09 volumio volumio[877]: info: ------------------------------------------- Oct 28 10:11:09 volumio ntpd[866]: Soliciting pool server 185.248.188.98 Oct 28 10:11:09 volumio ntpd[866]: Soliciting pool server 5.9.145.2 Oct 28 10:11:09 volumio ntpd[866]: Soliciting pool server 194.164.164.175 Oct 28 10:11:10 volumio volumio[877]: info: MYVOLUMIO Environment detected Oct 28 10:11:10 volumio volumio[877]: info: Plugin folders cleanup Oct 28 10:11:10 volumio volumio[877]: info: Scanning into folder /volumio/app/plugins/ Oct 28 10:11:10 volumio volumio[877]: info: Scanning category audio_interface Oct 28 10:11:10 volumio volumio[877]: info: Scanning category miscellanea Oct 28 10:11:10 volumio volumio[877]: info: Scanning category music_service Oct 28 10:11:10 volumio volumio[877]: info: Scanning category plugins.json Oct 28 10:11:10 volumio volumio[877]: info: Scanning category system_controller Oct 28 10:11:10 volumio volumio[877]: info: Scanning category user_interface Oct 28 10:11:10 volumio volumio[877]: info: Scanning into folder /data/plugins/ Oct 28 10:11:10 volumio volumio[877]: info: Plugin folders cleanup completed Oct 28 10:11:10 volumio volumio[877]: info: ------------------------------------------- Oct 28 10:11:10 volumio volumio[877]: info: ----- Core plugins startup ---- Oct 28 10:11:10 volumio volumio[877]: info: ------------------------------------------- Oct 28 10:11:10 volumio volumio[877]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 10:11:10 volumio volumio[877]: info: Adding plugin upnp to MyMusic Plugins Oct 28 10:11:10 volumio volumio[877]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 10:11:10 volumio volumio[877]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 10:11:10 volumio volumio[877]: info: Loading plugins from folder /data/plugins/ Oct 28 10:11:10 volumio volumio[877]: info: Loading plugin "system"... Oct 28 10:11:10 volumio volumio[877]: info: Loading plugin "appearance"... Oct 28 10:11:10 volumio ntpd[866]: Soliciting pool server 128.140.109.119 Oct 28 10:11:10 volumio ntpd[866]: Soliciting pool server 176.9.157.155 Oct 28 10:11:10 volumio ntpd[866]: Soliciting pool server 94.130.23.46 Oct 28 10:11:10 volumio ntpd[866]: Soliciting pool server 167.71.55.144 Oct 28 10:11:10 volumio nmbd[615]: [2024/10/28 10:11:10.982711, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 28 10:11:10 volumio nmbd[615]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 28 10:11:10 volumio systemd[1]: Started Samba NMB Daemon. Oct 28 10:11:11 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 28 10:11:11 volumio winbindd[907]: [2024/10/28 10:11:11.277002, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 28 10:11:11 volumio winbindd[907]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 28 10:11:11 volumio systemd[1]: Started Samba Winbind Daemon. Oct 28 10:11:11 volumio winbindd[907]: [2024/10/28 10:11:11.330996, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 28 10:11:11 volumio winbindd[907]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "network"... Oct 28 10:11:11 volumio volumio[877]: info: Refreshing Cached IP Addresses Oct 28 10:11:11 volumio systemd[1]: Starting Samba SMB Daemon... Oct 28 10:11:11 volumio sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 10:11:11 volumio sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "services"... Oct 28 10:11:11 volumio sudo[913]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:11 volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:11:11 volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "alsa_controller"... Oct 28 10:11:11 volumio sudo[916]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:11 volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 28 10:11:11 volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "wizard"... Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "networkfs"... Oct 28 10:11:11 volumio volumio[877]: info: Starting Udev Watcher for removable devices Oct 28 10:11:11 volumio sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=heinrich,password=0.3Pihalbe,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.90/music\\musik-heinz /mnt/NAS/Mucke Oct 28 10:11:11 volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:11 volumio volumio[877]: info: Ignoring mount for partition: boot Oct 28 10:11:11 volumio volumio[877]: info: Ignoring mount for partition: volumio Oct 28 10:11:11 volumio volumio[877]: info: Ignoring mount for partition: volumio_data Oct 28 10:11:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "volumio_command_line_client"... Oct 28 10:11:11 volumio volumio-remote-updater[520]: [2024-10-28 10:11:11] [connect] Successful connection Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "upnp"... Oct 28 10:11:11 volumio volumio[877]: info: [1730110271834] Starting Upmpd Daemon Oct 28 10:11:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "my_music"... Oct 28 10:11:11 volumio volumio[877]: info: Loading plugin "mpd"... Oct 28 10:11:11 volumio ntpd[866]: Soliciting pool server 144.76.66.156 Oct 28 10:11:11 volumio ntpd[866]: Soliciting pool server 136.243.177.133 Oct 28 10:11:11 volumio ntpd[866]: Soliciting pool server 162.159.200.1 Oct 28 10:11:12 volumio kernel: Key type cifs.spnego registered Oct 28 10:11:12 volumio kernel: Key type cifs.idmap registered Oct 28 10:11:12 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. Oct 28 10:11:12 volumio kernel: CIFS: Attempting to mount \\192.168.178.90\music Oct 28 10:11:12 volumio smbd[915]: [2024/10/28 10:11:12.615497, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 28 10:11:12 volumio systemd[1]: Started Samba SMB Daemon. Oct 28 10:11:12 volumio smbd[915]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 28 10:11:12 volumio systemd[1]: Reached target Multi-User System. Oct 28 10:11:12 volumio systemd[1]: Reached target Graphical Interface. Oct 28 10:11:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 28 10:11:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 28 10:11:12 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 28 10:11:12 volumio systemd[1]: Startup finished in 8.976s (kernel) + 32.497s (userspace) = 41.474s. Oct 28 10:11:12 volumio volumio[877]: info: Loading plugin "upnp_browser"... Oct 28 10:11:12 volumio ntpd[866]: Soliciting pool server 31.209.85.242 Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "alarm-clock"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "airplay_emulation"... Oct 28 10:11:13 volumio volumio[877]: info: Starting Shairport Sync Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "last_100"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "webradio"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "i2s_dacs"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "volumiodiscovery"... Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:13 volumio node[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:13 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** For more information see Oct 28 10:11:13 volumio node[877]: *** WARNING *** For more information see Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:13 volumio node[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:13 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:13 volumio volumio[877]: *** WARNING *** For more information see Oct 28 10:11:13 volumio node[877]: *** WARNING *** For more information see Oct 28 10:11:13 volumio volumio[877]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 10:11:13 volumio volumio[877]: info: Discovery: Started advertising with name: Volumio Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "outputs"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "albumart"... Oct 28 10:11:13 volumio volumio[877]: info: Plugin example_plugin is not enabled Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "inputs"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "updater_comm"... Oct 28 10:11:13 volumio sudo[927]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:13 volumio volumio[877]: info: Plugin mpdemulation is not enabled Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "rest_api"... Oct 28 10:11:13 volumio volumio[877]: info: Loading plugin "websocket"... Oct 28 10:11:13 volumio volumio[877]: Forking 3 albumart workers Oct 28 10:11:13 volumio volumio[877]: info: Loading i18n strings for locale de Oct 28 10:11:13 volumio volumio[877]: Updating browse sources language Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 10:11:13 volumio ntpd[866]: Soliciting pool server 2a01:4f8:c17:b041::1 Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::initPlayerControls Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:13 volumio volumio[877]: Express server listening on port 3000 Oct 28 10:11:13 volumio volumio[877]: [Metrics] WebUI: 5s 310.86ms Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::resetVolumioState Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::getcurrentVolume Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 10:11:14 volumio volumio-remote-updater[520]: [2024-10-28 10:11:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730110271 101 Oct 28 10:11:14 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Oct 28 10:11:14 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 2 Oct 28 10:11:14 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 3 Oct 28 10:11:14 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 4 Oct 28 10:11:14 volumio volumio[877]: Starting albumart workers Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 10:11:14 volumio volumio[877]: info: Reloading queue from file Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::setRepeat true single undefined Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::pushState Oct 28 10:11:14 volumio volumio[877]: Starting albumart workers Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::setRandom false Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::pushState Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:14 volumio volumio[877]: Starting albumart workers Oct 28 10:11:14 volumio volumio[877]: info: VolumeController:: Volume=100 Mute =false Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::pushState Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::updateTrackBlock Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrackBlock Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 10:11:14 volumio volumio[877]: info: Setting Device type: Raspberry PI Oct 28 10:11:14 volumio volumio[877]: info: VolumeController:: Volume=100 Mute =false Oct 28 10:11:14 volumio volumio[877]: info: CoreStateMachine::pushState Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:14 volumio volumio[877]: info: Discovery: adding 1b400d84-a6bf-496b-a9a6-64934280dca1 Oct 28 10:11:14 volumio volumio[877]: info: Discovery: Found device Volumio Oct 28 10:11:14 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Oct 28 10:11:14 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:14 volumio sudo[919]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:16 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 28 10:11:41 volumio ntpd[866]: receive: Unexpected origin timestamp 0xeac9e1c4.dffe575a does not match aorg 0000000000.00000000 from server@176.9.157.155 xmt 0xeac9e1dd.a43aeb4c Oct 28 10:11:41 volumio ntpd[866]: receive: Unexpected origin timestamp 0xeac9e1c4.dfff43ce does not match aorg 0000000000.00000000 from server@5.9.145.2 xmt 0xeac9e1dd.a4ed1d13 Oct 28 10:11:41 volumio ntpd[866]: receive: Unexpected origin timestamp 0xeac9e1c4.dffd68bc does not match aorg 0000000000.00000000 from server@167.71.55.144 xmt 0xeac9e1dd.a58fdaa9 Oct 28 10:11:41 volumio ntpd[866]: receive: Unexpected origin timestamp 0xeac9e1c4.dffb2df2 does not match aorg 0000000000.00000000 from server@144.76.66.156 xmt 0xeac9e1dd.a486cf2d Oct 28 10:11:41 volumio ntpd[866]: receive: Unexpected origin timestamp 0xeac9e1c4.dff7b616 does not match aorg 0000000000.00000000 from server@136.243.177.133 xmt 0xeac9e1dd.a4d5ad67 Oct 28 10:11:42 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 5 Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Oct 28 10:11:42 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 10:11:42 volumio volumio[877]: info: Listing playlists Oct 28 10:11:42 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 10:11:42 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:42 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:42 volumio sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:11:42 volumio sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:42 volumio sudo[1030]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:42 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 5 Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Oct 28 10:11:42 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 10:11:42 volumio volumio[877]: info: Listing playlists Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 10:11:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 10:11:43 volumio sh[428]: timed out Oct 28 10:11:43 volumio dhcpcd[472]: timed out Oct 28 10:11:43 volumio sh[428]: dhcpcd exited Oct 28 10:11:43 volumio dhcpcd[472]: dhcpcd exited Oct 28 10:11:43 volumio sh[428]: ifup: failed to bring up eth0 Oct 28 10:11:43 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 28 10:11:43 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 28 10:11:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 10:11:45 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:11:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 28 10:11:46 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 10:11:46 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:46 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:46 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:11:46 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:46 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:46 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 28 10:11:46 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:46 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 28 10:11:46 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:46 volumio volumio[877]: info: Upmpdcli Daemon Started Oct 28 10:11:46 volumio volumio[877]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 28 10:11:50 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:11:50 volumio volumio[877]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 28 10:11:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 28 10:11:52 volumio volumio[877]: verbose: ControllerMpd::sendMpdCommand rescan Oct 28 10:11:52 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:11:52 volumio volumio[877]: TypeError: Cannot read property 'then' of undefined Oct 28 10:11:52 volumio volumio[877]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Oct 28 10:11:52 volumio volumio[877]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) Oct 28 10:11:52 volumio volumio[877]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Oct 28 10:11:52 volumio volumio[877]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1045:26) Oct 28 10:11:52 volumio volumio[877]: at Socket.emit (events.js:315:20) Oct 28 10:11:52 volumio volumio[877]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 28 10:11:52 volumio volumio[877]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 28 10:11:52 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:11:53 volumio sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 10:10 Oct 28 10:11:53 volumio sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:53 volumio sudo[1058]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:53 volumio volumio-remote-updater[520]: [2024-10-28 10:11:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 10:11:53 volumio volumio-remote-updater[520]: [2024-10-28 10:11:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 10:11:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 28 10:11:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 28 10:11:53 volumio systemd[1]: Started dynamicswap service. Oct 28 10:11:53 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 28 10:11:53 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 28 10:11:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 28 10:11:53 volumio systemd[1]: Started dynamicswap service. Oct 28 10:11:53 volumio systemd[1]: Stopped Volumio Backend Module. Oct 28 10:11:53 volumio systemd[1]: Started Volumio Backend Module. Oct 28 10:11:53 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 28 10:11:54 volumio volumio[1072]: info: ------------------------------------------- Oct 28 10:11:54 volumio volumio[1072]: info: ----- Volumio3 ---- Oct 28 10:11:54 volumio volumio[1072]: info: ------------------------------------------- Oct 28 10:11:54 volumio volumio[1072]: info: ----- System startup ---- Oct 28 10:11:54 volumio volumio[1072]: info: ------------------------------------------- Oct 28 10:11:55 volumio volumio[1072]: info: MYVOLUMIO Environment detected Oct 28 10:11:55 volumio volumio[1072]: info: Plugin folders cleanup Oct 28 10:11:55 volumio volumio[1072]: info: Scanning into folder /volumio/app/plugins/ Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category audio_interface Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category miscellanea Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category music_service Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category plugins.json Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category system_controller Oct 28 10:11:55 volumio volumio[1072]: info: Scanning category user_interface Oct 28 10:11:55 volumio volumio[1072]: info: Scanning into folder /data/plugins/ Oct 28 10:11:55 volumio volumio[1072]: info: Plugin folders cleanup completed Oct 28 10:11:55 volumio volumio[1072]: info: ------------------------------------------- Oct 28 10:11:55 volumio volumio[1072]: info: ----- Core plugins startup ---- Oct 28 10:11:55 volumio volumio[1072]: info: ------------------------------------------- Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 10:11:55 volumio volumio[1072]: info: Adding plugin upnp to MyMusic Plugins Oct 28 10:11:55 volumio volumio[1072]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 10:11:55 volumio volumio[1072]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugins from folder /data/plugins/ Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "system"... Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "appearance"... Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "network"... Oct 28 10:11:55 volumio volumio[1072]: info: Refreshing Cached IP Addresses Oct 28 10:11:55 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 10:11:55 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:55 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "services"... Oct 28 10:11:55 volumio sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:11:55 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "alsa_controller"... Oct 28 10:11:55 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Oct 28 10:11:55 volumio sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 28 10:11:55 volumio sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:11:55 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "wizard"... Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "networkfs"... Oct 28 10:11:55 volumio volumio[1072]: info: Cannot mount NAS Mucke at system boot, trial number 1 ,retrying in 5 seconds Oct 28 10:11:55 volumio volumio[1072]: info: Starting Udev Watcher for removable devices Oct 28 10:11:55 volumio volumio[1072]: info: Ignoring mount for partition: boot Oct 28 10:11:55 volumio volumio[1072]: info: Ignoring mount for partition: volumio Oct 28 10:11:55 volumio volumio[1072]: info: Ignoring mount for partition: volumio_data Oct 28 10:11:55 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "volumio_command_line_client"... Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "upnp"... Oct 28 10:11:55 volumio volumio[1072]: info: [1730110315955] Starting Upmpd Daemon Oct 28 10:11:55 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "my_music"... Oct 28 10:11:55 volumio volumio[1072]: info: Loading plugin "mpd"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "upnp_browser"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "alarm-clock"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "airplay_emulation"... Oct 28 10:11:56 volumio volumio[1072]: info: Starting Shairport Sync Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "last_100"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "webradio"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "i2s_dacs"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "volumiodiscovery"... Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:56 volumio node[1072]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:56 volumio node[1072]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** For more information see Oct 28 10:11:56 volumio node[1072]: *** WARNING *** For more information see Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:56 volumio node[1072]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:56 volumio node[1072]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 10:11:56 volumio volumio[1072]: *** WARNING *** For more information see Oct 28 10:11:56 volumio node[1072]: *** WARNING *** For more information see Oct 28 10:11:56 volumio volumio[1072]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 10:11:56 volumio volumio[1072]: info: Discovery: Started advertising with name: Volumio Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "outputs"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "albumart"... Oct 28 10:11:56 volumio volumio[1072]: info: Plugin example_plugin is not enabled Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "inputs"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "updater_comm"... Oct 28 10:11:56 volumio volumio[1072]: info: Plugin mpdemulation is not enabled Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "rest_api"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading plugin "websocket"... Oct 28 10:11:56 volumio volumio[1072]: info: Loading i18n strings for locale de Oct 28 10:11:56 volumio volumio[1072]: Updating browse sources language Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 10:11:56 volumio volumio[1072]: Forking 3 albumart workers Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::initPlayerControls Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:11:56 volumio volumio[1072]: Express server listening on port 3000 Oct 28 10:11:56 volumio volumio[1072]: [Metrics] WebUI: 2s 425.13ms Oct 28 10:11:56 volumio volumio[1072]: info: CoreStateMachine::resetVolumioState Oct 28 10:11:56 volumio volumio[1072]: info: CoreStateMachine::getcurrentVolume Oct 28 10:11:56 volumio volumio[1072]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 10:11:57 volumio volumio[1072]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 1 Oct 28 10:11:57 volumio volumio[1072]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 10:11:57 volumio volumio[1072]: info: Reloading queue from file Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::setRepeat true single undefined Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::setRandom false Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:57 volumio volumio[1072]: info: Setting Device type: Raspberry PI Oct 28 10:11:57 volumio volumio[1072]: info: VolumeController:: Volume=100 Mute =false Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 10:11:57 volumio volumio[1072]: info: VolumeController:: Volume=100 Mute =false Oct 28 10:11:57 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 28 10:11:57 volumio volumio[1072]: Starting albumart workers Oct 28 10:11:57 volumio volumio[1072]: Starting albumart workers Oct 28 10:11:57 volumio volumio[1072]: Starting albumart workers Oct 28 10:11:57 volumio volumio[1072]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.199 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Total Clients: 2 Oct 28 10:11:57 volumio volumio[1072]: info: Discovery: adding 1b400d84-a6bf-496b-a9a6-64934280dca1 Oct 28 10:11:57 volumio volumio[1072]: info: Discovery: Found device Volumio Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 28 10:11:57 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:11:57 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 10:11:57 volumio volumio[1072]: info: Listing playlists Oct 28 10:11:58 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:11:58 volumio volumio-remote-updater[520]: [2024-10-28 10:11:58] [connect] Successful connection Oct 28 10:11:58 volumio volumio-remote-updater[520]: [2024-10-28 10:11:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730110318 101 Oct 28 10:11:58 volumio volumio[1072]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Oct 28 10:11:59 volumio sudo[1103]: pam_unix(sudo:session): session closed for user root Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 10:12:00 volumio volumio[1072]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 10:12:00 volumio volumio[1072]: info: Received Get System Version Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 10:12:00 volumio volumio[1072]: info: Received Get System Info Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:12:00 volumio volumio[1072]: info: Discovery: Getting this device information Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 28 10:12:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 28 10:12:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:12:00 volumio volumio[1072]: info: Cannot mount NAS Mucke at system boot, trial number 2 ,retrying in 5 seconds Oct 28 10:12:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:12:03 volumio wpa_supplicant[770]: RRM: Ignoring radio measurement request: Not RRM network Oct 28 10:12:05 volumio volumio[1072]: info: Cannot mount NAS Mucke at system boot, trial number 3 ,retrying in 5 seconds Oct 28 10:12:05 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 10:12:05 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:12:05 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Oct 28 10:12:05 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 10:12:05 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:12:06 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Oct 28 10:12:06 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 28 10:12:06 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 10:12:06 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Oct 28 10:12:06 volumio volumio[1072]: info: Upmpdcli Daemon Started Oct 28 10:12:06 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:12:06 volumio volumio[1072]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 28 10:12:10 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:12:10 volumio volumio[1072]: info: Cannot mount NAS Mucke at system boot, trial number 4 ,retrying in 5 seconds Oct 28 10:12:14 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:12:15 volumio volumio[1072]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 28 10:12:17 volumio volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 10:12:17 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 10:12:17 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 28 10:12:17 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 28 10:12:17 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 28 10:12:19 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 28 10:12:19 volumio volumio[1072]: info: CURURI: albums:// Oct 28 10:12:19 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:12:19 volumio volumio[1072]: TypeError: Cannot read property 'sendCommand' of undefined Oct 28 10:12:19 volumio volumio[1072]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 Oct 28 10:12:19 volumio volumio[1072]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 28 10:12:19 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:12:19 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 10:11 Oct 28 10:12:19 volumio sudo[1229]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"