-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sat 2024-11-23 13:37:52 UTC. -- Nov 23 13:36:00 volumio ifup[305]: command failed: No such device (-19) Nov 23 13:36:00 volumio avahi-daemon[338]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 23 13:36:00 volumio avahi-daemon[338]: Successfully dropped root privileges. Nov 23 13:36:00 volumio avahi-daemon[338]: avahi-daemon 0.7 starting up. Nov 23 13:36:00 volumio thd[354]: Found socket passed from systemd Nov 23 13:36:01 volumio systemd-logind[342]: New seat seat0. Nov 23 13:36:01 volumio haveged[330]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 23 13:36:01 volumio haveged[330]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 23 13:36:01 volumio haveged[330]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00092 Nov 23 13:36:01 volumio haveged[330]: haveged: fills: 0, generated: 0 Nov 23 13:36:01 volumio kernel: random: crng init done Nov 23 13:36:01 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Nov 23 13:36:01 volumio systemd[1]: Started Login Service. Nov 23 13:36:01 volumio systemd[1]: Started triggerhappy global hotkey daemon. Nov 23 13:36:01 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 23 13:36:01 volumio avahi-daemon[338]: Successfully called chroot(). Nov 23 13:36:01 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 23 13:36:01 volumio avahi-daemon[338]: Successfully dropped remaining capabilities. Nov 23 13:36:01 volumio systemd[1]: Failed to start dhcpcd on all interfaces. Nov 23 13:36:01 volumio systemd[1]: Started Save/Restore Sound Card State. Nov 23 13:36:01 volumio avahi-daemon[338]: No service file found in /etc/avahi/services. Nov 23 13:36:01 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 23 13:36:01 volumio systemd[1]: Reached target Sound Card. Nov 23 13:36:01 volumio avahi-daemon[338]: Network interface enumeration completed. Nov 23 13:36:01 volumio avahi-daemon[338]: Server startup complete. Host name is volumio.local. Local service cookie is 3495768084. Nov 23 13:36:01 volumio systemd[1]: Started RPC bind portmap service. Nov 23 13:36:01 volumio systemd[1]: Reached target Remote File Systems (Pre). Nov 23 13:36:01 volumio systemd[1]: Reached target Remote File Systems. Nov 23 13:36:01 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 23 13:36:01 volumio systemd[1]: Reached target RPC Port Mapper. Nov 23 13:36:02 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 23 13:36:02 volumio loadcpufreq[395]: Loading cpufreq kernel modules...done (none). Nov 23 13:36:02 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 23 13:36:02 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 23 13:36:03 volumio sh[306]: eth0: waiting for carrier Nov 23 13:36:03 volumio kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL) Nov 23 13:36:03 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 23 13:36:03 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 23 13:36:03 volumio dhcpcd[374]: eth0: waiting for carrier Nov 23 13:36:03 volumio bluealsa[346]: /usr/bin/bluealsa: D: ../../src/main.c:589: Starting main dispatching loop Nov 23 13:36:03 volumio bluealsa[346]: /usr/bin/bluealsa: D: ../../src/main.c:114: Acquired D-Bus service name: org.bluealsa Nov 23 13:36:03 volumio bluealsa[346]: /usr/bin/bluealsa: D: ../../src/bluealsa-dbus.c:368: Registering D-Bus manager: /org/bluealsa Nov 23 13:36:03 volumio dbus-daemon[344]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.3' (uid=0 pid=346 comm="/usr/bin/bluealsa --profile=a2dp-sink --codec=+apt") Nov 23 13:36:03 volumio systemd[1]: Condition check resulted in Bluetooth service being skipped. Nov 23 13:36:03 volumio cpufrequtils[436]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 23 13:36:03 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 23 13:36:03 volumio ifup[305]: command failed: No such device (-19) Nov 23 13:36:04 volumio systemd[1]: Started Raise network interfaces. Nov 23 13:36:04 volumio systemd-udevd[225]: Process '/sbin/crda' failed with exit code 249. Nov 23 13:36:04 volumio systemd[1]: iptables.service: Succeeded. Nov 23 13:36:05 volumio systemd[1]: Started WPA supplicant. Nov 23 13:36:05 volumio systemd[1]: Reached target Network. Nov 23 13:36:05 volumio wpa_supplicant[345]: Successfully initialized wpa_supplicant Nov 23 13:36:05 volumio systemd[1]: Starting Network Time Service... Nov 23 13:36:05 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 23 13:36:05 volumio systemd[1]: Starting Permit User Sessions... Nov 23 13:36:05 volumio systemd[1]: Starting OpenBSD Secure Shell server... Nov 23 13:36:05 volumio systemd[1]: Reached target Network is Online. Nov 23 13:36:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 23 13:36:05 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Nov 23 13:36:05 volumio systemd[1]: Starting Music Player Daemon... Nov 23 13:36:05 volumio systemd[1]: Starting Daily apt download activities... Nov 23 13:36:05 volumio systemd[1]: Starting Samba NMB Daemon... Nov 23 13:36:05 volumio systemd[1]: Starting /etc/rc.local Compatibility... Nov 23 13:36:05 volumio systemd[1]: Started Permit User Sessions. Nov 23 13:36:05 volumio systemd[1]: Started /etc/rc.local Compatibility. Nov 23 13:36:05 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 23 13:36:05 volumio systemd[1]: Starting Hold until boot process finishes up... Nov 23 13:36:05 volumio systemd[1]: plymouth-start.service: Succeeded. Nov 23 13:36:05 volumio systemd[1]: plymouth-quit.service: Succeeded. Nov 23 13:36:05 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Nov 23 13:36:06 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 23 13:36:06 volumio systemd[1]: Started Hold until boot process finishes up. Nov 23 13:36:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 231 (n/a). Nov 23 13:36:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 231 (n/a). Nov 23 13:36:06 volumio systemd[1]: Started Getty on tty1. Nov 23 13:36:06 volumio systemd[1]: Started Serial Getty on ttyAMA0. Nov 23 13:36:06 volumio systemd[1]: Reached target Login Prompts. Nov 23 13:36:06 volumio ifplugd(eth0)[565]: ifplugd 0.28 initializing. Nov 23 13:36:06 volumio ifplugd(eth0)[565]: Using interface eth0/B8:27:EB:13:E5:25 with driver (version: 5.10.92-v7+) Nov 23 13:36:06 volumio ifplugd(eth0)[565]: Using detection mode: SIOCETHTOOL Nov 23 13:36:06 volumio ifplugd(eth0)[565]: Initialization complete, link beat not detected. Nov 23 13:36:06 volumio ifplugd[540]: Network Interface Plugging Daemon...start eth0...done. Nov 23 13:36:06 volumio systemd[1]: Started LSB: Brings up/down network automatically. Nov 23 13:36:06 volumio volumio-remote-updater[343]: Error: No active session Nov 23 13:36:06 volumio volumio-remote-updater[343]: [2024-11-23 13:36:06] [info] asio async_connect error: system:111 (Connection refused) Nov 23 13:36:06 volumio volumio-remote-updater[343]: [2024-11-23 13:36:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 23 13:36:06 volumio volumio-remote-updater[343]: [2024-11-23 13:36:06] [error] handle_connect error: Underlying Transport Error Nov 23 13:36:06 volumio ntpd[535]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 23 13:36:06 volumio systemd[1]: Started Network Time Service. Nov 23 13:36:06 volumio ntpd[535]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 23 13:36:07 volumio ntpd[572]: proto: precision = 1.094 usec (-20) Nov 23 13:36:07 volumio ntpd[572]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 23 13:36:07 volumio ntpd[572]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 23 13:36:07 volumio ntpd[572]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 332 days ago Nov 23 13:36:07 volumio ntpd[572]: Listen and drop on 0 v6wildcard [::]:123 Nov 23 13:36:07 volumio ntpd[572]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 23 13:36:07 volumio ntpd[572]: Listen normally on 2 lo 127.0.0.1:123 Nov 23 13:36:07 volumio ntpd[572]: Listening on routing socket on fd #19 for interface updates Nov 23 13:36:07 volumio ntpd[572]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 23 13:36:07 volumio ntpd[572]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 23 13:36:07 volumio ifplugd(eth0)[565]: Link beat detected. Nov 23 13:36:07 volumio ifplugd(eth0)[565]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 23 13:36:07 volumio sh[306]: eth0: carrier acquired Nov 23 13:36:07 volumio dhcpcd[374]: eth0: carrier acquired Nov 23 13:36:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 23 13:36:07 volumio sshd[575]: Server listening on 0.0.0.0 port 22. Nov 23 13:36:07 volumio systemd[1]: Started OpenBSD Secure Shell server. Nov 23 13:36:07 volumio sshd[575]: Server listening on :: port 22. Nov 23 13:36:07 volumio ifplugd(eth0)[565]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 23 13:36:07 volumio dhcpcd[374]: DUID 00:01:00:01:2a:5e:ec:a3:b8:27:eb:3b:e7:97 Nov 23 13:36:07 volumio sh[306]: DUID 00:01:00:01:2a:5e:ec:a3:b8:27:eb:3b:e7:97 Nov 23 13:36:07 volumio sh[306]: eth0: IAID eb:13:e5:25 Nov 23 13:36:07 volumio sh[306]: eth0: adding address fe80::ba27:ebff:fe13:e525 Nov 23 13:36:07 volumio sh[306]: ipv6_addaddr1: Permission denied Nov 23 13:36:07 volumio dhcpcd[374]: eth0: IAID eb:13:e5:25 Nov 23 13:36:07 volumio dhcpcd[374]: eth0: adding address fe80::ba27:ebff:fe13:e525 Nov 23 13:36:07 volumio dhcpcd[374]: ipv6_addaddr1: Permission denied Nov 23 13:36:07 volumio sh[306]: eth0: soliciting a DHCP lease Nov 23 13:36:07 volumio dhcpcd[374]: eth0: soliciting a DHCP lease Nov 23 13:36:07 volumio sh[306]: eth0: offered 192.168.137.56 from 192.168.137.1 Nov 23 13:36:07 volumio dhcpcd[374]: eth0: offered 192.168.137.56 from 192.168.137.1 Nov 23 13:36:07 volumio sh[306]: eth0: probing address 192.168.137.56/24 Nov 23 13:36:07 volumio dhcpcd[374]: eth0: probing address 192.168.137.56/24 Nov 23 13:36:08 volumio ntpd[572]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 23 13:36:08 volumio sh[306]: eth0: soliciting an IPv6 router Nov 23 13:36:08 volumio dhcpcd[374]: eth0: soliciting an IPv6 router Nov 23 13:36:08 volumio volumio[355]: Could not open config: /tmp/upmpdcli.conf Nov 23 13:36:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 23 13:36:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 23 13:36:09 volumio ntpd[572]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 23 13:36:09 volumio kernel: cam1-reg: disabling Nov 23 13:36:09 volumio kernel: cam-dummy-reg: disabling Nov 23 13:36:10 volumio ntpd[572]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 23 13:36:11 volumio ntpd[572]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 23 13:36:11 volumio volumio-remote-updater[343]: [2024-11-23 13:36:11] [info] asio async_connect error: system:111 (Connection refused) Nov 23 13:36:11 volumio volumio-remote-updater[343]: [2024-11-23 13:36:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 23 13:36:11 volumio volumio-remote-updater[343]: [2024-11-23 13:36:11] [error] handle_connect error: Underlying Transport Error Nov 23 13:36:12 volumio nmbd[551]: [2024/11/23 13:36:12.192945, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 23 13:36:12 volumio nmbd[551]: started asyncdns process 604 Nov 23 13:36:12 volumio nmbd[551]: [2024/11/23 13:36:12.199135, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 23 13:36:12 volumio nmbd[551]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 23 13:36:12 volumio nmbd[551]: [2024/11/23 13:36:12.199535, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 23 13:36:12 volumio nmbd[551]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 23 13:36:12 volumio sh[306]: eth0: leased 192.168.137.56 for 604800 seconds Nov 23 13:36:12 volumio sh[306]: eth0: adding route to 192.168.137.0/24 Nov 23 13:36:12 volumio sh[306]: eth0: adding default route via 192.168.137.1 Nov 23 13:36:12 volumio dhcpcd[374]: eth0: leased 192.168.137.56 for 604800 seconds Nov 23 13:36:12 volumio dhcpcd[374]: eth0: adding route to 192.168.137.0/24 Nov 23 13:36:12 volumio dhcpcd[374]: eth0: adding default route via 192.168.137.1 Nov 23 13:36:12 volumio avahi-daemon[338]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.137.56. Nov 23 13:36:12 volumio avahi-daemon[338]: New relevant interface eth0.IPv4 for mDNS. Nov 23 13:36:12 volumio avahi-daemon[338]: Registering new address record for 192.168.137.56 on eth0.IPv4. Nov 23 13:36:12 volumio sh[306]: forked to background, child pid 626 Nov 23 13:36:12 volumio dhcpcd[374]: forked to background, child pid 626 Nov 23 13:36:13 volumio ifplugd(eth0)[565]: client: ifup: interface eth0 already configured Nov 23 13:36:13 volumio sh[306]: eth0=eth0 Nov 23 13:36:13 volumio ifplugd(eth0)[565]: Program executed successfully. Nov 23 13:36:14 volumio ntpd[572]: Listen normally on 3 eth0 192.168.137.56:123 Nov 23 13:36:14 volumio ntpd[572]: new interface(s) found: waking up resolver Nov 23 13:36:16 volumio systemd[1]: apt-daily.service: Succeeded. Nov 23 13:36:16 volumio systemd[1]: Started Daily apt download activities. Nov 23 13:36:16 volumio wireless.js[340]: WIRELESS: No wireless interface, exiting Nov 23 13:36:16 volumio iw[686]: command failed: No such device (-19) Nov 23 13:36:16 volumio iwconfig[689]: Error for wireless request "Set Power Management" (8B2C) : Nov 23 13:36:16 volumio iwconfig[689]: SET failed on device wlan0 ; No such device. Nov 23 13:36:16 volumio systemd[1]: wireless.service: Succeeded. Nov 23 13:36:16 volumio systemd[1]: Started Wireless Services. Nov 23 13:36:16 volumio systemd[1]: Started Volumio Backend Module. Nov 23 13:36:17 volumio nmbd[551]: [2024/11/23 13:36:17.338902, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 23 13:36:17 volumio nmbd[551]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 23 13:36:17 volumio systemd[1]: Started Samba NMB Daemon. Nov 23 13:36:17 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 23 13:36:17 volumio mpd[566]: Nov 23 13:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 13:36:17 volumio mpd[566]: Nov 23 13:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 23 13:36:18 volumio mpd[566]: Nov 23 13:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 23 13:36:18 volumio systemd[1]: Started Music Player Daemon. Nov 23 13:36:18 volumio winbindd[699]: [2024/11/23 13:36:18.292995, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 23 13:36:18 volumio winbindd[699]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 23 13:36:18 volumio winbindd[699]: [2024/11/23 13:36:18.330629, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 23 13:36:18 volumio winbindd[699]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 23 13:36:18 volumio systemd[1]: Started Samba Winbind Daemon. Nov 23 13:36:18 volumio systemd[1]: Starting Samba SMB Daemon... Nov 23 13:36:19 volumio volumio-remote-updater[343]: [2024-11-23 13:36:19] [info] asio async_connect error: system:111 (Connection refused) Nov 23 13:36:19 volumio volumio-remote-updater[343]: [2024-11-23 13:36:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 23 13:36:19 volumio volumio-remote-updater[343]: [2024-11-23 13:36:19] [error] handle_connect error: Underlying Transport Error Nov 23 13:36:19 volumio systemd[1]: Started Samba SMB Daemon. Nov 23 13:36:19 volumio smbd[706]: [2024/11/23 13:36:19.820503, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 23 13:36:19 volumio systemd[1]: Reached target Multi-User System. Nov 23 13:36:19 volumio smbd[706]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 23 13:36:19 volumio systemd[1]: Reached target Graphical Interface. Nov 23 13:36:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 23 13:36:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 23 13:36:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 23 13:36:19 volumio systemd[1]: Startup finished in 13.414s (kernel) + 32.362s (userspace) = 45.776s. Nov 23 13:36:22 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:22 volumio volumio[692]: info: ----- Volumio3 ---- Nov 23 13:36:22 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:22 volumio volumio[692]: info: ----- System startup ---- Nov 23 13:36:22 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:25 volumio volumio[692]: info: MYVOLUMIO Environment detected Nov 23 13:36:25 volumio volumio[692]: info: Plugin folders cleanup Nov 23 13:36:25 volumio volumio[692]: info: Scanning into folder /volumio/app/plugins/ Nov 23 13:36:25 volumio volumio[692]: info: Scanning category audio_interface Nov 23 13:36:25 volumio volumio[692]: info: Scanning category miscellanea Nov 23 13:36:25 volumio volumio[692]: info: Scanning category music_service Nov 23 13:36:25 volumio volumio[692]: info: Scanning category plugins.json Nov 23 13:36:25 volumio volumio[692]: info: Scanning category system_controller Nov 23 13:36:25 volumio volumio[692]: info: Scanning category user_interface Nov 23 13:36:25 volumio volumio[692]: info: Scanning into folder /data/plugins/ Nov 23 13:36:25 volumio volumio[692]: info: Scanning category music_service Nov 23 13:36:25 volumio volumio[692]: info: Scanning category user_interface Nov 23 13:36:25 volumio volumio[692]: info: Plugin folders cleanup completed Nov 23 13:36:25 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:25 volumio volumio[692]: info: ----- Core plugins startup ---- Nov 23 13:36:25 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:25 volumio volumio[692]: info: Loading plugins from folder /volumio/app/plugins/ Nov 23 13:36:25 volumio volumio[692]: info: Adding plugin upnp to MyMusic Plugins Nov 23 13:36:25 volumio volumio[692]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 23 13:36:25 volumio volumio[692]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 23 13:36:25 volumio volumio[692]: info: Loading plugins from folder /data/plugins/ Nov 23 13:36:25 volumio volumio[692]: info: Loading plugin "system"... Nov 23 13:36:25 volumio volumio[692]: info: Loading plugin "appearance"... Nov 23 13:36:27 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 23 13:36:28 volumio dbus-daemon[344]: [system] Failed to activate service 'org.bluez': timed out (service_start_timeout=25000ms) Nov 23 13:36:28 volumio bluealsa[346]: /usr/bin/bluealsa: W: ../../src/bluez.c:1003: Couldn't get managed objects: GDBus.Error:org.freedesktop.DBus.Error.TimedOut: Failed to activate service 'org.bluez': timed out (service_start_timeout=25000ms) Nov 23 13:36:28 volumio volumio[692]: info: Loading plugin "network"... Nov 23 13:36:28 volumio volumio[692]: info: Refreshing Cached IP Addresses Nov 23 13:36:28 volumio volumio[692]: info: Loading plugin "services"... Nov 23 13:36:28 volumio volumio[692]: info: Loading plugin "alsa_controller"... Nov 23 13:36:28 volumio sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 13:36:28 volumio sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 23 13:36:28 volumio sudo[726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 13:36:28 volumio sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:28 volumio sudo[726]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:28 volumio sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:29 volumio sudo[726]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:29 volumio sudo[730]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:29 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:36:29 volumio volumio[692]: info: Loading plugin "wizard"... Nov 23 13:36:29 volumio sudo[728]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:29 volumio volumio[692]: info: Loading plugin "volumio_command_line_client"... Nov 23 13:36:29 volumio volumio[692]: info: Loading plugin "upnp"... Nov 23 13:36:29 volumio volumio[692]: info: [1732368989122] Starting Upmpd Daemon Nov 23 13:36:29 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 13:36:29 volumio volumio[692]: info: Loading plugin "my_music"... Nov 23 13:36:29 volumio volumio-remote-updater[343]: [2024-11-23 13:36:29] [connect] Successful connection Nov 23 13:36:29 volumio volumio[692]: info: Loading plugin "mpd"... Nov 23 13:36:30 volumio volumio[692]: info: Creating MPD Configuration file Nov 23 13:36:30 volumio volumio[692]: info: Loading plugin "upnp_browser"... Nov 23 13:36:30 volumio sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 13:36:30 volumio sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:30 volumio sudo[741]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:30 volumio sudo[743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 13:36:30 volumio sudo[743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:30 volumio systemd[1]: Stopping Music Player Daemon... Nov 23 13:36:30 volumio systemd[1]: mpd.service: Succeeded. Nov 23 13:36:30 volumio systemd[1]: Stopped Music Player Daemon. Nov 23 13:36:30 volumio systemd[1]: Starting Music Player Daemon... Nov 23 13:36:31 volumio volumio[692]: info: Loading plugin "networkfs"... Nov 23 13:36:32 volumio volumio[692]: info: Starting Udev Watcher for removable devices Nov 23 13:36:32 volumio sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.68.65/contents /mnt/NAS/HVL1 Nov 23 13:36:32 volumio sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:32 volumio sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.68.58/disk1 /mnt/NAS/RECBOX Nov 23 13:36:32 volumio sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:32 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 23 13:36:32 volumio kernel: Key type cifs.spnego registered Nov 23 13:36:32 volumio kernel: Key type cifs.idmap registered Nov 23 13:36:32 volumio kernel: CIFS: Attempting to mount //192.168.68.65/contents Nov 23 13:36:32 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Nov 23 13:36:32 volumio kernel: CIFS: Attempting to mount //192.168.68.58/disk1 Nov 23 13:36:32 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Nov 23 13:36:32 volumio sudo[753]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:32 volumio volumio[692]: info: Ignoring mount for partition: boot Nov 23 13:36:32 volumio volumio[692]: info: Ignoring mount for partition: volumio Nov 23 13:36:32 volumio volumio[692]: info: Ignoring mount for partition: volumio_data Nov 23 13:36:32 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 13:36:32 volumio volumio[692]: info: Loading plugin "alarm-clock"... Nov 23 13:36:33 volumio mpd[748]: Nov 23 13:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 13:36:33 volumio mpd[748]: Nov 23 13:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 23 13:36:33 volumio mpd[748]: Nov 23 13:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 23 13:36:33 volumio systemd[1]: Started Music Player Daemon. Nov 23 13:36:33 volumio sudo[743]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:33 volumio volumio[692]: info: Loading plugin "airplay_emulation"... Nov 23 13:36:33 volumio volumio[692]: info: Starting Shairport Sync Nov 23 13:36:33 volumio volumio[692]: info: Loading plugin "last_100"... Nov 23 13:36:33 volumio volumio[692]: info: Loading plugin "webradio"... Nov 23 13:36:33 volumio volumio[692]: info: Loading plugin "i2s_dacs"... Nov 23 13:36:33 volumio volumio[692]: info: Loading plugin "volumiodiscovery"... Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 13:36:34 volumio node[692]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 13:36:34 volumio node[692]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** For more information see Nov 23 13:36:34 volumio node[692]: *** WARNING *** For more information see Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 13:36:34 volumio node[692]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 13:36:34 volumio node[692]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 13:36:34 volumio volumio[692]: *** WARNING *** For more information see Nov 23 13:36:34 volumio node[692]: *** WARNING *** For more information see Nov 23 13:36:34 volumio volumio[692]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 23 13:36:34 volumio volumio[692]: info: Discovery: Started advertising with name: Volumio Nov 23 13:36:34 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 13:36:34 volumio volumio[692]: info: Loading plugin "spop"... Nov 23 13:36:38 volumio volumio[692]: info: Loading plugin "now_playing"... Nov 23 13:36:42 volumio sudo[751]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:42 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 23 13:36:42 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Nov 23 13:36:42 volumio volumio[692]: info: Loading plugin "outputs"... Nov 23 13:36:42 volumio volumio[692]: info: Loading plugin "albumart"... Nov 23 13:36:42 volumio volumio[692]: info: Plugin example_plugin is not enabled Nov 23 13:36:42 volumio volumio[692]: info: Loading plugin "inputs"... Nov 23 13:36:42 volumio volumio[692]: info: Loading plugin "updater_comm"... Nov 23 13:36:43 volumio volumio[692]: info: Plugin mpdemulation is not enabled Nov 23 13:36:43 volumio volumio[692]: info: Loading plugin "rest_api"... Nov 23 13:36:43 volumio volumio[692]: info: Loading plugin "websocket"... Nov 23 13:36:43 volumio volumio[692]: info: Loading plugin "touch_display"... Nov 23 13:36:43 volumio volumio[692]: Forking 3 albumart workers Nov 23 13:36:44 volumio volumio-remote-updater[343]: [2024-11-23 13:36:44] [connect] Successful connection Nov 23 13:36:44 volumio volumio[692]: info: Applying required configuration parameters for plugin touch_display Nov 23 13:36:45 volumio volumio[692]: info: Loading i18n strings for locale ja Nov 23 13:36:45 volumio volumio[692]: Starting albumart workers Nov 23 13:36:45 volumio volumio[692]: Starting albumart workers Nov 23 13:36:45 volumio volumio[692]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 23 13:36:45 volumio volumio[692]: Updating browse sources language Nov 23 13:36:45 volumio volumio[692]: Starting albumart workers Nov 23 13:36:45 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::initPlayerControls Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:36:46 volumio volumio[692]: Express server listening on port 3000 Nov 23 13:36:46 volumio volumio[692]: [Metrics] WebUI: 25s 406.49ms Nov 23 13:36:46 volumio volumio[692]: info: CoreStateMachine::resetVolumioState Nov 23 13:36:46 volumio volumio[692]: info: CoreStateMachine::getcurrentVolume Nov 23 13:36:46 volumio volumio[692]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 13:36:46 volumio sudo[831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 13:36:46 volumio sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:46 volumio sudo[831]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:46 volumio sudo[834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 13:36:46 volumio sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:46 volumio sudo[834]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:46 volumio volumio[692]: info: CoreStateMachine::pushState Nov 23 13:36:46 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::volumioPushState Nov 23 13:36:47 volumio volumio[692]: info: CoreStateMachine::updateTrackBlock Nov 23 13:36:47 volumio volumio[692]: info: CorePlayQueue::getTrackBlock Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 13:36:47 volumio volumio[692]: info: Cannot mount NAS HVL1 at system boot, trial number 1 ,retrying in 5 seconds Nov 23 13:36:47 volumio volumio[692]: info: MPD Permissions set Nov 23 13:36:47 volumio volumio[692]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 23 13:36:47 volumio volumio[692]: wlan0 Interface doesn't support scanning. Nov 23 13:36:47 volumio volumio[692]: info: Cannot use regular scanning, forcing with ap-force Nov 23 13:36:47 volumio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 23 13:36:47 volumio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:47 volumio sudo[871]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:47 volumio volumio[692]: command failed: No such device (-19) Nov 23 13:36:47 volumio volumio[692]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 23 13:36:47 volumio volumio[692]: command failed: No such device (-19) Nov 23 13:36:47 volumio volumio-remote-updater[343]: [2024-11-23 13:36:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732369004 101 Nov 23 13:36:47 volumio volumio[692]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Nov 23 13:36:47 volumio volumio[692]: info: MPD running with PID748 Nov 23 13:36:47 volumio volumio[692]: ,establishing connection Nov 23 13:36:47 volumio volumio[692]: info: CoreStateMachine::pushState Nov 23 13:36:47 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::volumioPushState Nov 23 13:36:47 volumio volumio[692]: error: MPD error: The expression evaluated to a falsy value: Nov 23 13:36:47 volumio volumio[692]: assert.ok(self.idling) Nov 23 13:36:47 volumio volumio[692]: error: The expression evaluated to a falsy value: Nov 23 13:36:47 volumio volumio[692]: assert.ok(self.idling) Nov 23 13:36:47 volumio volumio[692]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:47 volumio volumio[692]: info: Reloading queue from file Nov 23 13:36:48 volumio volumio[692]: info: Setting Device type: Raspberry PI Nov 23 13:36:48 volumio volumio[692]: info: CoreStateMachine::setRepeat false single undefined Nov 23 13:36:48 volumio volumio[692]: info: CoreStateMachine::pushState Nov 23 13:36:48 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::volumioPushState Nov 23 13:36:48 volumio volumio[692]: info: CoreStateMachine::setRandom null Nov 23 13:36:48 volumio volumio[692]: info: CoreStateMachine::pushState Nov 23 13:36:48 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::volumioPushState Nov 23 13:36:48 volumio volumio[692]: error: updateQueue error: null Nov 23 13:36:48 volumio volumio[692]: info: Completed loading Core Plugins Nov 23 13:36:48 volumio volumio[692]: info: Preparing to generate the ALSA configuration file Nov 23 13:36:48 volumio volumio[692]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 23 13:36:48 volumio volumio[692]: info: Reading ALSA contributions from plugins. Nov 23 13:36:48 volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 23 13:36:48 volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 23 13:36:48 volumio sudo[885]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:48 volumio volumio[692]: info: Upmpdcli Daemon Started Nov 23 13:36:48 volumio volumio[692]: info: Asound.conf file unchanged, so no further update is needed Nov 23 13:36:48 volumio volumio[692]: info: Output device has changed, restarting MPD Nov 23 13:36:48 volumio volumio[692]: info: Output device has changed, restarting Shairport Sync Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:48 volumio sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 13:36:48 volumio sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:48 volumio sudo[890]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:48 volumio sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 13:36:48 volumio sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:48 volumio systemd[1]: Stopping Music Player Daemon... Nov 23 13:36:48 volumio volumio[692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 13:36:48 volumio volumio[692]: info: ___________ START PLUGINS ___________ Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 13:36:48 volumio volumio[692]: info: [1732369008675] CoreMusicLibrary::Adding element Media Servers Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 13:36:48 volumio systemd[1]: mpd.service: Succeeded. Nov 23 13:36:48 volumio systemd[1]: Stopped Music Player Daemon. Nov 23 13:36:48 volumio systemd[1]: Starting Music Player Daemon... Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:48 volumio volumio[692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 13:36:48 volumio volumio[692]: info: [1732369008947] CoreMusicLibrary::Adding element Last_100 Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 13:36:48 volumio volumio[692]: info: [1732369008956] CoreMusicLibrary::Adding element Webradio Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 13:36:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:36:48 volumio volumio[692]: info: Checking for old spotify connect plugin installed Nov 23 13:36:49 volumio volumio[692]: info: [now-playing-config] Config is up to date. Nov 23 13:36:49 volumio volumio[692]: info: [now-playing-weather] Fetching API key... Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:36:49 volumio volumio[692]: info: Discovery: Getting this device information Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:49 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:36:49 volumio volumio[692]: info: Loading i18n strings for locale ja Nov 23 13:36:49 volumio volumio[692]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:36:49 volumio volumio[692]: info: Volumio Calling Home Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 13:36:49 volumio volumio[692]: [SpotifyConnect] Creating VLS config file Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:49 volumio volumio[692]: [SpotifyConnect] Starting metadata listener Nov 23 13:36:49 volumio volumio[692]: info: [now-playing-app] App is listening on port 4004. Nov 23 13:36:49 volumio sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Nov 23 13:36:49 volumio sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:49 volumio sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 23 13:36:49 volumio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 23 13:36:49 volumio sudo[911]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:49 volumio volumio[692]: info: Discovery: adding 88618222-80fc-4754-be1c-cc53fba93019 Nov 23 13:36:49 volumio volumio[692]: info: Discovery: Found device Volumio Nov 23 13:36:49 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:49 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:49 volumio systemd[1]: Reloading. Nov 23 13:36:49 volumio volumio[692]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 23 13:36:49 volumio volumio[692]: [Metrics] SpotifyConnect: 0s 353.93ms Nov 23 13:36:49 volumio volumio[692]: info: MPD Permissions set Nov 23 13:36:49 volumio volumio[692]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Nov 23 13:36:50 volumio volumio[692]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:36:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:36:50 volumio volumio[916]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 23 13:36:50 volumio volumio[916]: Reading Config from "/tmp/volspotify.toml" Nov 23 13:36:50 volumio volumio[692]: info: Volumio called home Nov 23 13:36:50 volumio volumio[692]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 23 13:36:50 volumio volumio[692]: info: Starting Shairport Sync Nov 23 13:36:50 volumio volumio[916]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 23 13:36:50 volumio volumio[692]: info: Starting Shairport Sync Nov 23 13:36:50 volumio volumio[692]: info: Starting Shairport Sync Nov 23 13:36:50 volumio sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 13:36:50 volumio sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:50 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 13:36:50 volumio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 13:36:50 volumio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:50 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 13:36:50 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:51 volumio volumio[916]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 23 13:36:51 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:51 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:51 volumio volumio[916]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Nov 23 13:36:51 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 13:36:51 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:51 volumio sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 13:36:51 volumio sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:51 volumio systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 23 13:36:51 volumio sudo[949]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 23 13:36:51 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio volumio[692]: (node:692) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '1' of null Nov 23 13:36:51 volumio volumio[692]: at doGet (/data/plugins/user_interface/now_playing/lib/api/openweathermap/index.js:74:60) Nov 23 13:36:51 volumio volumio[692]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 23 13:36:51 volumio volumio[692]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 23 13:36:51 volumio volumio[692]: (node:692) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Nov 23 13:36:51 volumio volumio[692]: (node:692) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 23 13:36:51 volumio volumio[692]: verbose: New Socket.io Connection to 192.168.137.56 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 5 Nov 23 13:36:51 volumio sudo[914]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio volumio[692]: info: touch_display: systemctl daemon-reload succeeded. Nov 23 13:36:51 volumio mpd[906]: Nov 23 13:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 13:36:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 23 13:36:51 volumio sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 23 13:36:51 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 23 13:36:51 volumio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 23 13:36:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 23 13:36:51 volumio sudo[939]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio sudo[941]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio sudo[936]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio mpd[906]: Nov 23 13:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 23 13:36:51 volumio mpd[906]: Nov 23 13:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 23 13:36:51 volumio volumio[916]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Nov 23 13:36:51 volumio volumio[916]: stack backtrace: Nov 23 13:36:51 volumio systemd[1]: Started Music Player Daemon. Nov 23 13:36:51 volumio sudo[892]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:51 volumio volumio[692]: info: Shairport-Sync Started Nov 23 13:36:51 volumio volumio[692]: Error adding Membership: Error: addMembership EINVAL Nov 23 13:36:51 volumio volumio[692]: info: Shairport-Sync Started Nov 23 13:36:52 volumio systemd[1]: Started Volumio Kiosk. Nov 23 13:36:52 volumio volumio[692]: info: Shairport-Sync Started Nov 23 13:36:52 volumio sudo[957]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:52 volumio sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 13:36:52 volumio sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:52 volumio sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 13:36:52 volumio sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:52 volumio sudo[961]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:52 volumio volumio[692]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 23 13:36:52 volumio volumio[692]: info: touch_display: Volumio Kiosk started Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:52 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:52 volumio sudo[966]: pam_unix(sudo:session): session closed for user root Nov 23 13:36:52 volumio volumio[692]: info: Completed starting Core Plugins Nov 23 13:36:52 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:52 volumio volumio[692]: info: ----- MyVolumio plugins startup ---- Nov 23 13:36:52 volumio volumio[692]: info: ------------------------------------------- Nov 23 13:36:52 volumio volumio[692]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:52 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:52 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Nov 23 13:36:52 volumio sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.68.65/contents /mnt/NAS/HVL1 Nov 23 13:36:52 volumio sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:36:52 volumio volumio[692]: error: updateQueue error: null Nov 23 13:36:52 volumio volumio[692]: verbose: New Socket.io Connection to 192.168.137.56 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 6 Nov 23 13:36:52 volumio kernel: CIFS: Attempting to mount //192.168.68.65/contents Nov 23 13:36:52 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 13:36:52 volumio volumio[692]: Upnp client error: Error: read ECONNRESET Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:52 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 13:36:52 volumio volumio[692]: info: Received Get System Info Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:36:52 volumio volumio[692]: info: Discovery: Getting this device information Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:52 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:52 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:52 volumio volumio[692]: info: Listing playlists Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 23 13:36:52 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 13:36:52 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Nov 23 13:36:52 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 23 13:36:52 volumio volumio[887]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 23 13:36:53 volumio volumio[916]: 0: 0x9c8444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Nov 23 13:36:53 volumio volumio[916]: 1: 0x9c8444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Nov 23 13:36:53 volumio volumio[916]: 2: 0x9c8444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Nov 23 13:36:53 volumio volumio[916]: 3: 0x9c8444 - ::fmt::h33b23b1d14f668ff Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Nov 23 13:36:53 volumio volumio[916]: 4: 0x9f10dc - core::fmt::write::h6320fb0e6ac90e58 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Nov 23 13:36:53 volumio volumio[916]: 5: 0x9c04c4 - std::io::Write::write_fmt::h33b25d4810ca481f Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Nov 23 13:36:53 volumio volumio[916]: 6: 0x9caef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Nov 23 13:36:53 volumio volumio[916]: 7: 0x9caef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Nov 23 13:36:53 volumio volumio[916]: 8: 0x9caef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Nov 23 13:36:53 volumio volumio[916]: 9: 0x9cab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Nov 23 13:36:53 volumio volumio[916]: 10: 0x9cb694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Nov 23 13:36:53 volumio volumio[916]: 11: 0x9cb304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Nov 23 13:36:53 volumio volumio[916]: 12: 0x9c89c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Nov 23 13:36:53 volumio volumio[916]: 13: 0x9cb098 - rust_begin_unwind Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Nov 23 13:36:53 volumio volumio[916]: 14: 0x4ae6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Nov 23 13:36:53 volumio volumio[916]: 15: 0x69a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Nov 23 13:36:53 volumio volumio[916]: 16: 0x718d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Nov 23 13:36:53 volumio volumio[916]: 17: 0x69b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Nov 23 13:36:53 volumio volumio[916]: 18: 0x7115f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Nov 23 13:36:53 volumio volumio[916]: 19: 0x4d5b34 - ::poll::h5fd9e3bcdeb25ad9 Nov 23 13:36:53 volumio volumio[916]: 20: 0x4f33dc - futures::task_impl::std::set::h8f754e80bd562d0a Nov 23 13:36:53 volumio volumio[916]: 21: 0x50a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Nov 23 13:36:53 volumio volumio[916]: 22: 0x4ef99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Nov 23 13:36:53 volumio volumio[916]: 23: 0x50a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Nov 23 13:36:53 volumio volumio[916]: 24: 0x4f2880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Nov 23 13:36:53 volumio volumio[692]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 23 13:36:53 volumio volumio[916]: 25: 0x4d8eb0 - vollibrespot::main::h35555355ed947dd1 Nov 23 13:36:53 volumio volumio[916]: 26: 0x4f8c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Nov 23 13:36:53 volumio volumio[916]: 27: 0x51e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Nov 23 13:36:53 volumio volumio[916]: 28: 0x9c7b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Nov 23 13:36:53 volumio volumio[916]: 29: 0x9c7b28 - std::panicking::try::do_call::hdf194554a41aa3cc Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Nov 23 13:36:53 volumio volumio[916]: 30: 0x9c7b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Nov 23 13:36:53 volumio volumio[916]: 31: 0x9c7b28 - std::panic::catch_unwind::h45f392a39f2fe48c Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Nov 23 13:36:53 volumio volumio[916]: 32: 0x9c7b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Nov 23 13:36:53 volumio volumio[916]: 33: 0x9c7b28 - std::panicking::try::do_call::hd126f715b519ff2a Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Nov 23 13:36:53 volumio volumio[916]: 34: 0x9c7b28 - std::panicking::try::h80b7148ce71893dc Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Nov 23 13:36:53 volumio volumio[916]: 35: 0x9c7b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Nov 23 13:36:53 volumio volumio[916]: 36: 0x9c7b28 - std::rt::lang_start_internal::h3daa586a0b835507 Nov 23 13:36:53 volumio volumio[916]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Nov 23 13:36:53 volumio volumio[916]: 37: 0x4dae80 - main Nov 23 13:36:53 volumio volumio[916]: 38: 0x76c4c768 - __libc_start_main Nov 23 13:36:53 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Nov 23 13:36:53 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 23 13:36:53 volumio startx[968]: X.Org X Server 1.20.4 Nov 23 13:36:53 volumio startx[968]: X Protocol Version 11, Revision 0 Nov 23 13:36:53 volumio startx[968]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Nov 23 13:36:53 volumio startx[968]: Current Operating System: Linux volumio 5.10.92-v7+ #1514 SMP Mon Jan 17 17:36:39 GMT 2022 armv7l Nov 23 13:36:53 volumio startx[968]: Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Nov 23 13:36:53 volumio startx[968]: Build Date: 04 August 2022 12:56:42PM Nov 23 13:36:53 volumio startx[968]: xorg-server 2:1.20.4-1+rpt4+deb10u5 (https://www.debian.org/support) Nov 23 13:36:53 volumio startx[968]: Current version of pixman: 0.36.0 Nov 23 13:36:53 volumio startx[968]: Before reporting problems, check http://wiki.x.org Nov 23 13:36:53 volumio startx[968]: to make sure that you have the latest version. Nov 23 13:36:53 volumio startx[968]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 23 13:36:53 volumio startx[968]: (++) from command line, (!!) notice, (II) informational, Nov 23 13:36:53 volumio startx[968]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 23 13:36:53 volumio startx[968]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Nov 23 13:36:53 2024 Nov 23 13:36:53 volumio startx[968]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 23 13:36:53 volumio startx[968]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 23 13:36:53 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 23 13:36:54 volumio volumio[692]: info: Checking Spotify Web API Nov 23 13:36:54 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:54 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:54 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:55 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 13:36:55 volumio volumio[692]: info: Received Get System Info Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:36:55 volumio volumio[692]: info: Discovery: Getting this device information Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:55 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 13:36:55 volumio volumio[692]: info: Received Get System Info Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:36:55 volumio volumio[692]: info: Discovery: Getting this device information Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:36:55 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:36:55 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 23 13:36:55 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Nov 23 13:36:55 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Nov 23 13:36:55 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 23 13:36:55 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 23 13:36:56 volumio volumio[1028]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 23 13:36:56 volumio volumio[1028]: Reading Config from "/tmp/volspotify.toml" Nov 23 13:36:56 volumio volumio[1028]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 23 13:36:56 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 23 13:36:56 volumio volumio[1028]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 23 13:36:56 volumio volumio[1028]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Nov 23 13:36:56 volumio volumio[1028]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Nov 23 13:36:56 volumio volumio[1028]: stack backtrace: Nov 23 13:36:56 volumio volumio[1028]: 0: 0xa18444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Nov 23 13:36:56 volumio volumio[1028]: 1: 0xa18444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Nov 23 13:36:56 volumio volumio[1028]: 2: 0xa18444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Nov 23 13:36:56 volumio volumio[1028]: 3: 0xa18444 - ::fmt::h33b23b1d14f668ff Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Nov 23 13:36:56 volumio volumio[1028]: 4: 0xa410dc - core::fmt::write::h6320fb0e6ac90e58 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Nov 23 13:36:56 volumio volumio[1028]: 5: 0xa104c4 - std::io::Write::write_fmt::h33b25d4810ca481f Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Nov 23 13:36:56 volumio volumio[1028]: 6: 0xa1aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Nov 23 13:36:56 volumio volumio[1028]: 7: 0xa1aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Nov 23 13:36:56 volumio volumio[1028]: 8: 0xa1aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Nov 23 13:36:56 volumio volumio[1028]: 9: 0xa1ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Nov 23 13:36:56 volumio volumio[1028]: 10: 0xa1b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Nov 23 13:36:56 volumio volumio[1028]: 11: 0xa1b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Nov 23 13:36:56 volumio volumio[1028]: 12: 0xa189c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Nov 23 13:36:56 volumio volumio[1028]: 13: 0xa1b098 - rust_begin_unwind Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Nov 23 13:36:56 volumio volumio[1028]: 14: 0x4fe6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Nov 23 13:36:56 volumio volumio[1028]: 15: 0x6ea930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Nov 23 13:36:56 volumio volumio[1028]: 16: 0x768d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Nov 23 13:36:56 volumio volumio[1028]: 17: 0x6eb53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Nov 23 13:36:56 volumio volumio[1028]: 18: 0x7615f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Nov 23 13:36:56 volumio volumio[1028]: 19: 0x525b34 - ::poll::h5fd9e3bcdeb25ad9 Nov 23 13:36:56 volumio volumio[1028]: 20: 0x5433dc - futures::task_impl::std::set::h8f754e80bd562d0a Nov 23 13:36:56 volumio volumio[1028]: 21: 0x55a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Nov 23 13:36:56 volumio volumio[1028]: 22: 0x53f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Nov 23 13:36:56 volumio volumio[1028]: 23: 0x55a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Nov 23 13:36:56 volumio volumio[1028]: 24: 0x542880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Nov 23 13:36:56 volumio volumio[1028]: 25: 0x528eb0 - vollibrespot::main::h35555355ed947dd1 Nov 23 13:36:56 volumio volumio[1028]: 26: 0x548c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Nov 23 13:36:56 volumio volumio[1028]: 27: 0x56e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Nov 23 13:36:56 volumio volumio[1028]: 28: 0xa17b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Nov 23 13:36:56 volumio volumio[1028]: 29: 0xa17b28 - std::panicking::try::do_call::hdf194554a41aa3cc Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Nov 23 13:36:56 volumio volumio[1028]: 30: 0xa17b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Nov 23 13:36:56 volumio volumio[1028]: 31: 0xa17b28 - std::panic::catch_unwind::h45f392a39f2fe48c Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Nov 23 13:36:56 volumio volumio[1028]: 32: 0xa17b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Nov 23 13:36:56 volumio volumio[1028]: 33: 0xa17b28 - std::panicking::try::do_call::hd126f715b519ff2a Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Nov 23 13:36:56 volumio volumio[1028]: 34: 0xa17b28 - std::panicking::try::h80b7148ce71893dc Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Nov 23 13:36:56 volumio volumio[1028]: 35: 0xa17b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Nov 23 13:36:56 volumio volumio[1028]: 36: 0xa17b28 - std::rt::lang_start_internal::h3daa586a0b835507 Nov 23 13:36:56 volumio volumio[1028]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Nov 23 13:36:56 volumio volumio[1028]: 37: 0x52ae80 - main Nov 23 13:36:56 volumio volumio[1028]: 38: 0x76c0b768 - __libc_start_main Nov 23 13:36:56 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Nov 23 13:36:56 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 23 13:36:57 volumio volumio[887]: ................++++ Nov 23 13:36:58 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Nov 23 13:36:58 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Nov 23 13:36:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 23 13:36:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 23 13:36:59 volumio volumio[1034]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 23 13:36:59 volumio volumio[1034]: Reading Config from "/tmp/volspotify.toml" Nov 23 13:36:59 volumio volumio[1034]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 23 13:36:59 volumio volumio[1034]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 23 13:36:59 volumio volumio[1034]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Nov 23 13:36:59 volumio volumio[1034]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111) Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin bluetooth to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin multiroom to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin metavolumio to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin cd_controller to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 23 13:37:00 volumio volumio[692]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 23 13:37:01 volumio startx[968]: [1B blob data] Nov 23 13:37:02 volumio sudo[988]: pam_unix(sudo:session): session closed for user root Nov 23 13:37:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 23 13:37:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Nov 23 13:37:03 volumio startx[968]: waiting for X server to begin accepting connections . Nov 23 13:37:03 volumio volumio[692]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 23 13:37:03 volumio volumio[692]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 23 13:37:03 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:03 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:03 volumio volumio[692]: info: Starting MyVolumio Remote Streaming Endpoints Nov 23 13:37:04 volumio volumio[692]: info: MyVolumio login type: Token Nov 23 13:37:04 volumio volumio[692]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 23 13:37:04 volumio volumio[692]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 23 13:37:07 volumio volumio[692]: info: Starting Streaming Service Transparent Proxy Nov 23 13:37:07 volumio volumio[692]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 23 13:37:07 volumio volumio[692]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 23 13:37:07 volumio volumio[692]: info: Streaming services startup Nov 23 13:37:07 volumio volumio[692]: info: Starting Streaming Daemon Nov 23 13:37:07 volumio sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 13:37:07 volumio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:37:07 volumio volumio[692]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 23 13:37:07 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root Nov 23 13:37:07 volumio volumio[692]: error: Cannot start Volumio Streaming Daemon Nov 23 13:37:07 volumio volumio[692]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 13:37:07 volumio volumio[692]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 13:37:07 volumio volumio[692]: info: Cannot mount NAS HVL1 at system boot, trial number 2 ,retrying in 5 seconds Nov 23 13:37:07 volumio volumio[692]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Nov 23 13:37:07 volumio volumio[692]: /usr/bin/xset: unable to open display ":0" Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:08 volumio volumio[692]: info: Retrieving Cloud Streaming UI Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:37:08 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:37:08 volumio volumio[692]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 23 13:37:08 volumio volumio[692]: error: MyVolumio Custom Token format not valid, refreshing it Nov 23 13:37:08 volumio volumio[692]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 23 13:37:08 volumio volumio[692]: info: Getting Tidal Cloud Configuration Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:08 volumio volumio[692]: info: Getting Qobuz Cloud Configuration Nov 23 13:37:08 volumio volumio[692]: info: Asking plugin for UI Config Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:08 volumio volumio[692]: info: Getting Spotify Cloud Configuration Nov 23 13:37:08 volumio volumio[692]: info: Asking plugin for UI Config Nov 23 13:37:08 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:08 volumio volumio[692]: info: Saving Spotify Acccount Nov 23 13:37:08 volumio volumio[692]: info: Got Tidal Cloud Configuration Nov 23 13:37:08 volumio volumio[692]: info: Got it Nov 23 13:37:08 volumio volumio[692]: info: Got it Nov 23 13:37:08 volumio volumio[692]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:09 volumio volumio[692]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 13:37:09 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 13:37:10 volumio volumio[692]: info: MyVolumio login type: Token Nov 23 13:37:10 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 13:37:10 volumio volumio[692]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 23 13:37:10 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 23 13:37:11 volumio volumio[692]: info: Received Get System Version Nov 23 13:37:11 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:37:11 volumio volumio[692]: info: Received Get System Info Nov 23 13:37:11 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:37:11 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:37:11 volumio volumio[692]: info: Discovery: Getting this device information Nov 23 13:37:11 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:37:11 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:37:11 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:37:11 volumio volumio[692]: STREAMING PROXY: Starting server on port 3245 Nov 23 13:37:11 volumio volumio[692]: Node JS runtime: 14 Nov 23 13:37:11 volumio volumio[692]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 23 13:37:11 volumio volumio[692]: info: MYVOLUMIO: Adding device Nov 23 13:37:11 volumio volumio[692]: info: MYVOLUMIO: Evaluating Server Nov 23 13:37:12 volumio volumio[692]: info: MyVolumio status changed Nov 23 13:37:12 volumio volumio[692]: info: Streaming services startup Nov 23 13:37:12 volumio volumio[692]: info: Starting Streaming Daemon Nov 23 13:37:12 volumio volumio[692]: info: Removing browser output: myVolumio user plan is not superstar Nov 23 13:37:12 volumio volumio[692]: info: Removing audio output: Nov 23 13:37:12 volumio volumio[692]: info: Stoppping Tunnel 1 Nov 23 13:37:12 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 13:37:12 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:37:12 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 23 13:37:12 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:37:12 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Nov 23 13:37:12 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Nov 23 13:37:12 volumio volumio[692]: error: Cannot start Volumio Streaming Daemon Nov 23 13:37:12 volumio volumio[692]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 13:37:12 volumio volumio[692]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 13:37:12 volumio volumio[692]: info: Setting Geolocation for MyVolumio to as1 Nov 23 13:37:12 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:12 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:12 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:12 volumio volumio[692]: info: Remote SSH Stopped Nov 23 13:37:12 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.68.65/contents /mnt/NAS/HVL1 Nov 23 13:37:12 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:37:13 volumio kernel: CIFS: Attempting to mount //192.168.68.65/contents Nov 23 13:37:13 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Nov 23 13:37:13 volumio volumio[692]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 13:37:13 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 13:37:13 volumio volumio[692]: info: Updating MyVolumio device info Nov 23 13:37:13 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:13 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:13 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:14 volumio ntpd[572]: Soliciting pool server 202.181.103.212 Nov 23 13:37:14 volumio ntpd[572]: Soliciting pool server 133.130.121.141 Nov 23 13:37:14 volumio volumio[692]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 13:37:14 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 23 13:37:15 volumio ntpd[572]: Soliciting pool server 129.250.35.251 Nov 23 13:37:15 volumio ntpd[572]: Soliciting pool server 172.105.204.105 Nov 23 13:37:15 volumio ntpd[572]: Soliciting pool server 202.182.111.234 Nov 23 13:37:15 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 13:37:16 volumio ntpd[572]: Soliciting pool server 162.159.200.1 Nov 23 13:37:16 volumio ntpd[572]: Soliciting pool server 163.44.97.196 Nov 23 13:37:16 volumio ntpd[572]: Soliciting pool server 167.179.119.205 Nov 23 13:37:16 volumio ntpd[572]: Soliciting pool server 133.243.238.243 Nov 23 13:37:17 volumio ntpd[572]: Soliciting pool server 45.76.211.39 Nov 23 13:37:17 volumio ntpd[572]: Soliciting pool server 133.243.238.163 Nov 23 13:37:17 volumio ntpd[572]: Soliciting pool server 129.250.35.250 Nov 23 13:37:17 volumio ntpd[572]: Soliciting pool server 108.160.132.224 Nov 23 13:37:17 volumio volumio[692]: info: MYVOLUMIO: Adding device Nov 23 13:37:17 volumio volumio[692]: info: MYVOLUMIO: Evaluating Server Nov 23 13:37:17 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 13:37:18 volumio ntpd[572]: Soliciting pool server 45.77.20.103 Nov 23 13:37:18 volumio ntpd[572]: Soliciting pool server 2406:da14:10c4:9aa0:123:: Nov 23 13:37:18 volumio volumio[692]: info: Setting Geolocation for MyVolumio to as1 Nov 23 13:37:18 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:18 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:18 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:18 volumio volumio[692]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 13:37:18 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 13:37:19 volumio volumio[692]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Nov 23 13:37:19 volumio volumio[692]: info: Updating MyVolumio device info Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:37:19 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 13:37:19 volumio volumio-remote-updater[343]: No test mode Nov 23 13:37:19 volumio volumio-remote-updater[343]: No alpha test mode Nov 23 13:37:19 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 23 13:37:20 volumio volumio[692]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 13:37:20 volumio volumio[692]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix Multiroom Buffer Sizes
    • Increase results for Kana-Kanji Conversion
    • Fix for streaming interruptions

 

NEW ADDITIONS

    • Bump RPi kernel to 6.6.56, support for RPi5 2GB
    • Dedicated entry for RPi5 on some I2S DACs, restored master mode for older RPi models
    • Add support for HifiBerry AMP4 Pro on RPi

 

 

","title":"Update v3.779","updateavailable":true} Nov 23 13:37:20 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 23 13:37:21 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 13:37:21 volumio volumio[887]: ......................................................................................................++++ Nov 23 13:37:21 volumio volumio[887]: e is 65537 (0x010001) Nov 23 13:37:45 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeaec5912.00702065 does not match aorg 0000000000.00000000 from server@133.243.238.243 xmt 0xeaec5929.22265f76 Nov 23 13:37:45 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeaec5912.00812876 does not match aorg 0000000000.00000000 from server@202.181.103.212 xmt 0xeaec5929.2226a4aa Nov 23 13:37:45 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeaec5912.006a4652 does not match aorg 0000000000.00000000 from server@133.243.238.163 xmt 0xeaec5929.2232ef96 Nov 23 13:37:45 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeaec5912.005912d1 does not match aorg 0000000000.00000000 from server@45.76.211.39 xmt 0xeaec5929.222ee42f Nov 23 13:37:45 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeaec5912.007487b7 does not match aorg 0000000000.00000000 from server@202.182.111.234 xmt 0xeaec5929.223dc3fa Nov 23 13:37:45 volumio volumio[887]: writing RSA key Nov 23 13:37:46 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Nov 23 13:37:46 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 23 13:37:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Nov 23 13:37:46 volumio volumio[692]: info: Cannot mount NAS HVL1 at system boot, trial number 3 ,retrying in 5 seconds Nov 23 13:37:46 volumio volumio[692]: info: CoreCommandRouter::volumioGetState Nov 23 13:37:46 volumio volumio[692]: info: CorePlayQueue::getTrack 0 Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 13:37:47 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:37:48 volumio volumio[692]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 13:37:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 13:37:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 13:37:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 13:37:48 volumio volumio[692]: info: Received Get System Version Nov 23 13:37:48 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 13:37:50 volumio volumio[692]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 23 13:37:50 volumio volumio[692]: info: Completed starting MyVolumio Plugin Nov 23 13:37:50 volumio volumio[692]: info: BOOT COMPLETED Nov 23 13:37:50 volumio volumio[692]: [Metrics] CommandRouter: 64s 651.09ms Nov 23 13:37:50 volumio volumio[692]: info: CoreCommandRouter::volumiosetStartupVolume Nov 23 13:37:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 13:37:50 volumio volumio[692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:37:50 volumio volumio[692]: info: CoreCommandRouter::Close All Modals sent Nov 23 13:37:50 volumio volumio[692]: info: CoreCommandRouter::Close All Modals sent Nov 23 13:37:51 volumio volumio[692]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 13:37:51 volumio volumio[692]: TypeError: Cannot read property 'replace' of undefined Nov 23 13:37:51 volumio volumio[692]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 23 13:37:51 volumio volumio[692]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 23 13:37:51 volumio volumio[692]: at listOnTimeout (internal/timers.js:554:17) Nov 23 13:37:51 volumio volumio[692]: at processTimers (internal/timers.js:497:7) Nov 23 13:37:51 volumio volumio[692]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 13:37:52 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 13:36 Nov 23 13:37:52 volumio sudo[1261]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"