-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-05-11 15:58:30 CEST. -- May 11 15:58:00 olsdorf-volumio ntpd[763]: receive: Unexpected origin timestamp 0xe9cbf672.cdfd433b does not match aorg 0000000000.00000000 from server@185.252.140.126 xmt 0xe9e9f7e8.30382c35 May 11 15:58:00 olsdorf-volumio ntpd[763]: receive: Unexpected origin timestamp 0xe9cbf672.ce02cc8d does not match aorg 0000000000.00000000 from server@144.76.59.106 xmt 0xe9e9f7e8.303202bc May 11 15:58:00 olsdorf-volumio ntpd[763]: receive: Unexpected origin timestamp 0xe9cbf672.ce08333b does not match aorg 0000000000.00000000 from server@85.215.189.120 xmt 0xe9e9f7e8.30ebaf36 May 11 15:58:00 olsdorf-volumio systemd[1]: Starting Daily apt download activities... May 11 15:58:00 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:00 olsdorf-volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:00 olsdorf-volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:00 olsdorf-volumio sudo[993]: pam_unix(sudo:session): session closed for user root May 11 15:58:01 olsdorf-volumio systemd[1]: apt-daily.service: Succeeded. May 11 15:58:01 olsdorf-volumio systemd[1]: Started Daily apt download activities. May 11 15:58:01 olsdorf-volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 11 15:58:01 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:01 olsdorf-volumio sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:01 olsdorf-volumio sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:01 olsdorf-volumio sudo[1055]: pam_unix(sudo:session): session closed for user root May 11 15:58:02 olsdorf-volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 11 15:58:02 olsdorf-volumio systemd[1]: Started Daily apt upgrade and clean activities. May 11 15:58:02 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:02 olsdorf-volumio sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:02 olsdorf-volumio sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:02 olsdorf-volumio sudo[1078]: pam_unix(sudo:session): session closed for user root May 11 15:58:03 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:03 olsdorf-volumio sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:03 olsdorf-volumio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:03 olsdorf-volumio sudo[1081]: pam_unix(sudo:session): session closed for user root May 11 15:58:04 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:04 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:04 olsdorf-volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:04 olsdorf-volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:04 olsdorf-volumio sudo[1084]: pam_unix(sudo:session): session closed for user root May 11 15:58:05 olsdorf-volumio dhcpcd[874]: wlan0: leased 192.168.178.30 for 864000 seconds May 11 15:58:05 olsdorf-volumio avahi-daemon[520]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.30. May 11 15:58:05 olsdorf-volumio avahi-daemon[520]: New relevant interface wlan0.IPv4 for mDNS. May 11 15:58:05 olsdorf-volumio avahi-daemon[520]: Registering new address record for 192.168.178.30 on wlan0.IPv4. May 11 15:58:05 olsdorf-volumio dhcpcd[874]: wlan0: adding route to 192.168.178.0/24 May 11 15:58:05 olsdorf-volumio dhcpcd[874]: wlan0: adding default route via 192.168.178.1 May 11 15:58:05 olsdorf-volumio ntpd[763]: ntpd exiting on signal 15 (Terminated) May 11 15:58:05 olsdorf-volumio ntpd[763]: 192.168.178.1 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 193.37.152.32 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 185.232.69.65 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 131.188.3.220 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 176.9.157.155 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 85.215.189.120 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 141.82.25.202 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio systemd[1]: Stopping Network Time Service... May 11 15:58:05 olsdorf-volumio ntpd[763]: 144.76.59.106 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 62.128.1.19 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 185.252.140.126 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 144.76.139.8 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 162.159.200.1 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 85.220.190.246 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 144.76.0.164 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio systemd[1]: ntp.service: Succeeded. May 11 15:58:05 olsdorf-volumio ntpd[763]: 176.9.90.88 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 45.142.247.74 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio ntpd[763]: 116.202.171.176 local addr 192.168.178.27 -> May 11 15:58:05 olsdorf-volumio systemd[1]: Stopped Network Time Service. May 11 15:58:05 olsdorf-volumio systemd[1]: Starting Network Time Service... May 11 15:58:05 olsdorf-volumio ntpd[1118]: ntpd 4.2.8p12@1.3728-o (1): Starting May 11 15:58:05 olsdorf-volumio ntpd[1118]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 May 11 15:58:05 olsdorf-volumio systemd[1]: Started Network Time Service. May 11 15:58:05 olsdorf-volumio ntpd[1125]: proto: precision = 0.729 usec (-20) May 11 15:58:05 olsdorf-volumio ntpd[1125]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 11 15:58:05 olsdorf-volumio ntpd[1125]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listen and drop on 0 v6wildcard [::]:123 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listen normally on 2 lo 127.0.0.1:123 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listen normally on 3 eth0 192.168.178.27:123 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listen normally on 4 wlan0 192.168.178.30:123 May 11 15:58:05 olsdorf-volumio ntpd[1125]: Listening on routing socket on fd #21 for interface updates May 11 15:58:05 olsdorf-volumio ntpd[1125]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 11 15:58:05 olsdorf-volumio ntpd[1125]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 11 15:58:05 olsdorf-volumio wireless.js[513]: trying... May 11 15:58:05 olsdorf-volumio sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 11 15:58:05 olsdorf-volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:05 olsdorf-volumio sudo[1128]: pam_unix(sudo:session): session closed for user root May 11 15:58:05 olsdorf-volumio wireless.js[513]: Connected to: ----OlsdorfNet_1.1 May 11 15:58:05 olsdorf-volumio wireless.js[513]: ---- May 11 15:58:05 olsdorf-volumio sudo[1131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 15:58:05 olsdorf-volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:05 olsdorf-volumio sudo[1131]: pam_unix(sudo:session): session closed for user root May 11 15:58:05 olsdorf-volumio wireless.js[513]: ... joined AP, wlan0 IPv4 is 192.168.178.30, ipV6 is undefined May 11 15:58:05 olsdorf-volumio wireless.js[513]: It's done! AP May 11 15:58:06 olsdorf-volumio systemd[1]: Started Wireless Services. May 11 15:58:06 olsdorf-volumio systemd[1]: Started Volumio Backend Module. May 11 15:58:06 olsdorf-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 11 15:58:06 olsdorf-volumio systemd[1]: Started Volumio Cpu Tweaker. May 11 15:58:06 olsdorf-volumio systemd[1]: Reached target Multi-User System. May 11 15:58:06 olsdorf-volumio systemd[1]: Reached target Graphical Interface. May 11 15:58:06 olsdorf-volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 11 15:58:06 olsdorf-volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: Setting RT Priority for mpd May 11 15:58:06 olsdorf-volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: pid 35's current scheduling policy: SCHED_OTHER May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: pid 35's current scheduling priority: 0 May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: Setting MPD Affinity May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: pid 3's current affinity mask: f May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 11 15:58:06 olsdorf-volumio volumio-cpu-tweak[1137]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 11 15:58:06 olsdorf-volumio systemd[1]: Startup finished in 10.993s (kernel) + 29.936s (userspace) = 40.930s. May 11 15:58:06 olsdorf-volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 11 15:58:06 olsdorf-volumio ntpd[1125]: Soliciting pool server 193.37.152.32 May 11 15:58:07 olsdorf-volumio ntpd[1125]: Soliciting pool server 185.232.69.65 May 11 15:58:07 olsdorf-volumio ntpd[1125]: Soliciting pool server 131.188.3.220 May 11 15:58:08 olsdorf-volumio volumio-remote-updater[518]: [2024-05-11 15:58:08] [connect] Successful connection May 11 15:58:08 olsdorf-volumio volumio[1136]: info: ------------------------------------------- May 11 15:58:08 olsdorf-volumio volumio[1136]: info: ----- Volumio3 ---- May 11 15:58:08 olsdorf-volumio volumio[1136]: info: ------------------------------------------- May 11 15:58:08 olsdorf-volumio volumio[1136]: info: ----- System startup ---- May 11 15:58:08 olsdorf-volumio volumio[1136]: info: ------------------------------------------- May 11 15:58:08 olsdorf-volumio ntpd[1125]: Soliciting pool server 176.9.157.155 May 11 15:58:08 olsdorf-volumio ntpd[1125]: Soliciting pool server 85.215.189.120 May 11 15:58:08 olsdorf-volumio ntpd[1125]: Soliciting pool server 141.82.25.202 May 11 15:58:09 olsdorf-volumio ntpd[1125]: Soliciting pool server 144.76.59.106 May 11 15:58:09 olsdorf-volumio ntpd[1125]: Soliciting pool server 185.252.140.126 May 11 15:58:09 olsdorf-volumio ntpd[1125]: Soliciting pool server 62.128.1.19 May 11 15:58:09 olsdorf-volumio ntpd[1125]: Soliciting pool server 144.76.139.8 May 11 15:58:09 olsdorf-volumio volumio[1136]: info: MYVOLUMIO Environment detected May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Plugin folders cleanup May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning into folder /volumio/app/plugins/ May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category audio_interface May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category miscellanea May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category music_service May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category plugins.json May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category system_controller May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning category user_interface May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Scanning into folder /data/plugins/ May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Plugin folders cleanup completed May 11 15:58:09 olsdorf-volumio volumio[1136]: info: ------------------------------------------- May 11 15:58:09 olsdorf-volumio volumio[1136]: info: ----- Core plugins startup ---- May 11 15:58:09 olsdorf-volumio volumio[1136]: info: ------------------------------------------- May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Loading plugins from folder /volumio/app/plugins/ May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Adding plugin upnp to MyMusic Plugins May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Adding plugin airplay_emulation to MyMusic Plugins May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Adding plugin upnp_browser to MyMusic Plugins May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Loading plugins from folder /data/plugins/ May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Loading plugin "system"... May 11 15:58:09 olsdorf-volumio volumio[1136]: info: Loading plugin "appearance"... May 11 15:58:09 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:10 olsdorf-volumio ntpd[1125]: Soliciting pool server 144.76.0.164 May 11 15:58:10 olsdorf-volumio ntpd[1125]: Soliciting pool server 162.159.200.1 May 11 15:58:10 olsdorf-volumio ntpd[1125]: Soliciting pool server 85.220.190.246 May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "network"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Refreshing Cached IP Addresses May 11 15:58:11 olsdorf-volumio sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 15:58:11 olsdorf-volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:11 olsdorf-volumio sudo[1169]: pam_unix(sudo:session): session closed for user root May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "services"... May 11 15:58:11 olsdorf-volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 15:58:11 olsdorf-volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:11 olsdorf-volumio sudo[1171]: pam_unix(sudo:session): session closed for user root May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "alsa_controller"... May 11 15:58:11 olsdorf-volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 11 15:58:11 olsdorf-volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:11 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "wizard"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "networkfs"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Starting Udev Watcher for removable devices May 11 15:58:11 olsdorf-volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicuser,password=Mu3x9g27%21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //DiskStation/music /mnt/NAS/DiskStation May 11 15:58:11 olsdorf-volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Ignoring mount for partition: boot May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Ignoring mount for partition: volumio May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Ignoring mount for partition: volumio_data May 11 15:58:11 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "volumio_command_line_client"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "upnp"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: [1715435891641] Starting Upmpd Daemon May 11 15:58:11 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "my_music"... May 11 15:58:11 olsdorf-volumio volumio[1136]: info: Loading plugin "mpd"... May 11 15:58:11 olsdorf-volumio ntpd[1125]: Soliciting pool server 176.9.90.88 May 11 15:58:11 olsdorf-volumio ntpd[1125]: Soliciting pool server 45.142.247.74 May 11 15:58:11 olsdorf-volumio kernel: Key type cifs.spnego registered May 11 15:58:11 olsdorf-volumio kernel: Key type cifs.idmap registered May 11 15:58:11 olsdorf-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 11 15:58:11 olsdorf-volumio kernel: CIFS: Attempting to mount \\DiskStation\music May 11 15:58:12 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "upnp_browser"... May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "alarm-clock"... May 11 15:58:12 olsdorf-volumio ntpd[1125]: Soliciting pool server 116.202.171.176 May 11 15:58:12 olsdorf-volumio ntpd[1125]: Soliciting pool server 2a01:4f8:10b:4655::2 May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Plugin airplay_emulation is not enabled May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "last_100"... May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "webradio"... May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "i2s_dacs"... May 11 15:58:12 olsdorf-volumio volumio[1136]: info: Loading plugin "volumiodiscovery"... May 11 15:58:12 olsdorf-volumio systemd[1]: systemd-fsckd.service: Succeeded. May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** For more information see May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 15:58:13 olsdorf-volumio volumio[1136]: *** WARNING *** For more information see May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** For more information see May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! May 11 15:58:13 olsdorf-volumio node[1136]: *** WARNING *** For more information see May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Applying required configuration parameters for plugin volumiodiscovery May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Discovery: Started advertising with name: Olsdorf-Volumio May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "outputs"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "albumart"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Plugin example_plugin is not enabled May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "inputs"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "updater_comm"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Plugin mpdemulation is not enabled May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "rest_api"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading plugin "websocket"... May 11 15:58:13 olsdorf-volumio volumio[1136]: info: Loading i18n strings for locale de May 11 15:58:13 olsdorf-volumio volumio[1136]: Updating browse sources language May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 15:58:13 olsdorf-volumio volumio[1136]: Forking 3 albumart workers May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::initPlayerControls May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:13 olsdorf-volumio volumio[1136]: Express server listening on port 3000 May 11 15:58:13 olsdorf-volumio volumio[1136]: [Metrics] WebUI: 6s 30.63ms May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreStateMachine::resetVolumioState May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreStateMachine::getcurrentVolume May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioRetrievevolume May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreStateMachine::pushState May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:58:13 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioPushState May 11 15:58:14 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Reloading queue from file May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreStateMachine::setRepeat true single undefined May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreStateMachine::pushState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioPushState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreStateMachine::setRandom false May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreStateMachine::pushState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioPushState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Setting Device type: Raspberry PI May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Discovery: adding 1e3b0c80-1b22-45b4-9605-f076903d6e68 May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Discovery: Found device Olsdorf-Volumio May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Discovery: this is already registered, 1e3b0c80-1b22-45b4-9605-f076903d6e68 May 11 15:58:14 olsdorf-volumio volumio[1136]: info: Discovery: Found device Olsdorf-Volumio May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:14 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:14 olsdorf-volumio volumio[1136]: Starting albumart workers May 11 15:58:14 olsdorf-volumio volumio[1136]: Starting albumart workers May 11 15:58:14 olsdorf-volumio volumio[1136]: Starting albumart workers May 11 15:58:14 olsdorf-volumio sudo[1174]: pam_unix(sudo:session): session closed for user root May 11 15:58:15 olsdorf-volumio sudo[1180]: pam_unix(sudo:session): session closed for user root May 11 15:58:16 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:16 olsdorf-volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 11 15:58:18 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:18 olsdorf-volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 15:58:18 olsdorf-volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:18 olsdorf-volumio sudo[1272]: pam_unix(sudo:session): session closed for user root May 11 15:58:18 olsdorf-volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 15:58:18 olsdorf-volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:18 olsdorf-volumio sudo[1274]: pam_unix(sudo:session): session closed for user root May 11 15:58:18 olsdorf-volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.178.27 from 192.168.178.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 1 May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 15:58:18 olsdorf-volumio volumio[1136]: info: Received Get System Info May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 15:58:18 olsdorf-volumio volumio[1136]: info: Discovery: Getting this device information May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:18 olsdorf-volumio volumio[1136]: info: Listing playlists May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 11 15:58:18 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 15:58:19 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:19 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 15:58:20 olsdorf-volumio volumio[1136]: info: Received Get System Info May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 15:58:20 olsdorf-volumio volumio[1136]: info: Discovery: Getting this device information May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetState May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CorePlayQueue::getTrack 0 May 11 15:58:20 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 15:58:21 olsdorf-volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 15:58:21 olsdorf-volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:21 olsdorf-volumio sudo[1278]: pam_unix(sudo:session): session closed for user root May 11 15:58:21 olsdorf-volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 15:58:21 olsdorf-volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:21 olsdorf-volumio sudo[1280]: pam_unix(sudo:session): session closed for user root May 11 15:58:21 olsdorf-volumio sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 11 15:58:21 olsdorf-volumio sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 15:58:21 olsdorf-volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 11 15:58:21 olsdorf-volumio sudo[1284]: pam_unix(sudo:session): session closed for user root May 11 15:58:21 olsdorf-volumio volumio[1136]: info: Upmpdcli Daemon Started May 11 15:58:21 olsdorf-volumio volumio[1136]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 11 15:58:23 olsdorf-volumio volumio-remote-updater[518]: [2024-05-11 15:58:23] [connect] Successful connection May 11 15:58:23 olsdorf-volumio volumio-remote-updater[518]: [2024-05-11 15:58:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715435903 101 May 11 15:58:23 olsdorf-volumio volumio[1136]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 May 11 15:58:23 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:25 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetBrowseSources May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetBrowseSources May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::volumioGetBrowseSources May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 15:58:25 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 11 15:58:25 olsdorf-volumio volumio[1136]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 11 15:58:27 olsdorf-volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network May 11 15:58:29 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 15:58:30 olsdorf-volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 11 15:58:30 olsdorf-volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand update May 11 15:58:30 olsdorf-volumio volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 15:58:30 olsdorf-volumio volumio[1136]: TypeError: Cannot read property 'then' of undefined May 11 15:58:30 olsdorf-volumio volumio[1136]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) May 11 15:58:30 olsdorf-volumio volumio[1136]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) May 11 15:58:30 olsdorf-volumio volumio[1136]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) May 11 15:58:30 olsdorf-volumio volumio[1136]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) May 11 15:58:30 olsdorf-volumio volumio[1136]: at Socket.emit (events.js:315:20) May 11 15:58:30 olsdorf-volumio volumio[1136]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 11 15:58:30 olsdorf-volumio volumio[1136]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 11 15:58:30 olsdorf-volumio volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 15:58:30 olsdorf-volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 15:57 May 11 15:58:30 olsdorf-volumio sudo[1299]: 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"