-- 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"