-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-22 16:30:23 CEST. -- May 22 16:29:19 volumio-miro systemd-timedated[1093]: Changed local time to Thu May 22 16:29:19 2025 May 22 16:29:19 volumio-miro sudo[1087]: pam_unix(sudo:session): session closed for user root May 22 16:29:19 volumio-miro systemd[1]: Starting Daily man-db regeneration... May 22 16:29:19 volumio-miro systemd[1]: Starting Daily apt download activities... May 22 16:29:19 volumio-miro volumio-time-update[549]: volumio-time-update-util: System time updated successfully. May 22 16:29:19 volumio-miro systemd[1]: Started Volumio Time Update Utility. May 22 16:29:19 volumio-miro sh[500]: eth0: leased 192.168.68.108 for 7200 seconds May 22 16:29:19 volumio-miro sh[500]: eth0: adding route to 192.168.68.0/24 May 22 16:29:19 volumio-miro sh[500]: eth0: adding default route via 192.168.68.1 May 22 16:29:19 volumio-miro dhcpcd[533]: eth0: leased 192.168.68.108 for 7200 seconds May 22 16:29:19 volumio-miro dhcpcd[533]: eth0: adding route to 192.168.68.0/24 May 22 16:29:19 volumio-miro dhcpcd[533]: eth0: adding default route via 192.168.68.1 May 22 16:29:19 volumio-miro sh[500]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. May 22 16:29:19 volumio-miro wireless.js[546]: trying... May 22 16:29:19 volumio-miro sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 22 16:29:19 volumio-miro systemd[1]: man-db.service: Succeeded. May 22 16:29:19 volumio-miro systemd[1]: Started Daily man-db regeneration. May 22 16:29:19 volumio-miro sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:19 volumio-miro sudo[1134]: pam_unix(sudo:session): session closed for user root May 22 16:29:19 volumio-miro ntpd[1083]: ntpd exiting on signal 15 (Terminated) May 22 16:29:19 volumio-miro ntpd[1083]: 17.253.14.251 local addr 192.168.68.108 -> May 22 16:29:19 volumio-miro systemd[1]: Stopping Network Time Service... May 22 16:29:19 volumio-miro systemd[1]: ntp.service: Succeeded. May 22 16:29:19 volumio-miro systemd[1]: Stopped Network Time Service. May 22 16:29:19 volumio-miro systemd[1]: Starting Network Time Service... May 22 16:29:19 volumio-miro ntpd[1165]: ntpd 4.2.8p12@1.3728-o (1): Starting May 22 16:29:19 volumio-miro ntpd[1165]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 May 22 16:29:19 volumio-miro systemd[1]: Started Network Time Service. May 22 16:29:19 volumio-miro ntpd[1172]: proto: precision = 0.875 usec (-20) May 22 16:29:19 volumio-miro ntpd[1172]: Listen and drop on 0 v6wildcard [::]:123 May 22 16:29:19 volumio-miro ntpd[1172]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 22 16:29:19 volumio-miro ntpd[1172]: Listen normally on 2 lo 127.0.0.1:123 May 22 16:29:19 volumio-miro ntpd[1172]: Listen normally on 3 eth0 192.168.68.108:123 May 22 16:29:19 volumio-miro ntpd[1172]: Listening on routing socket on fd #20 for interface updates May 22 16:29:19 volumio-miro ntpd[1172]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 22 16:29:19 volumio-miro ntpd[1172]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 22 16:29:19 volumio-miro sh[500]: forked to background, child pid 1173 May 22 16:29:19 volumio-miro dhcpcd[533]: forked to background, child pid 1173 May 22 16:29:19 volumio-miro ifplugd(eth0)[800]: client: ifup: interface eth0 already configured May 22 16:29:19 volumio-miro sh[500]: eth0=eth0 May 22 16:29:19 volumio-miro ifplugd(eth0)[800]: Program executed successfully. May 22 16:29:19 volumio-miro ifplugd[759]: Network Interface Plugging Daemon...start eth0...done. May 22 16:29:19 volumio-miro systemd[1]: Started LSB: Brings up/down network automatically. May 22 16:29:20 volumio-miro wireless.js[546]: trying... May 22 16:29:20 volumio-miro sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 22 16:29:20 volumio-miro sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:20 volumio-miro sudo[1202]: pam_unix(sudo:session): session closed for user root May 22 16:29:20 volumio-miro kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. May 22 16:29:20 volumio-miro systemd[1]: Started Samba NMB Daemon. May 22 16:29:20 volumio-miro nmbd[774]: [2025/05/22 16:29:20.382366, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 16:29:20 volumio-miro systemd[1]: Starting Samba Winbind Daemon... May 22 16:29:20 volumio-miro nmbd[774]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 22 16:29:20 volumio-miro systemd[1]: apt-daily.service: Succeeded. May 22 16:29:20 volumio-miro systemd[1]: Started Daily apt download activities. May 22 16:29:20 volumio-miro systemd[1]: Starting Daily apt upgrade and clean activities... May 22 16:29:20 volumio-miro winbindd[1231]: [2025/05/22 16:29:20.621836, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 22 16:29:20 volumio-miro winbindd[1231]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 22 16:29:20 volumio-miro winbindd[1231]: [2025/05/22 16:29:20.632132, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 16:29:20 volumio-miro winbindd[1231]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 22 16:29:20 volumio-miro systemd[1]: Started Samba Winbind Daemon. May 22 16:29:20 volumio-miro systemd[1]: Starting Samba SMB Daemon... May 22 16:29:20 volumio-miro dhcpcd[1084]: wlan0: leased 192.168.68.116 for 7200 seconds May 22 16:29:20 volumio-miro avahi-daemon[560]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.116. May 22 16:29:20 volumio-miro avahi-daemon[560]: New relevant interface wlan0.IPv4 for mDNS. May 22 16:29:20 volumio-miro dhcpcd[1084]: wlan0: adding route to 192.168.68.0/24 May 22 16:29:20 volumio-miro dhcpcd[1084]: wlan0: adding default route via 192.168.68.1 May 22 16:29:20 volumio-miro avahi-daemon[560]: Registering new address record for 192.168.68.116 on wlan0.IPv4. May 22 16:29:20 volumio-miro ntpd[1172]: ntpd exiting on signal 15 (Terminated) May 22 16:29:20 volumio-miro ntpd[1172]: 17.253.14.251 local addr 192.168.68.108 -> May 22 16:29:20 volumio-miro systemd[1]: Stopping Network Time Service... May 22 16:29:20 volumio-miro systemd[1]: ntp.service: Succeeded. May 22 16:29:20 volumio-miro systemd[1]: Stopped Network Time Service. May 22 16:29:20 volumio-miro systemd[1]: Starting Network Time Service... May 22 16:29:20 volumio-miro ntpd[1305]: ntpd 4.2.8p12@1.3728-o (1): Starting May 22 16:29:20 volumio-miro ntpd[1305]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 May 22 16:29:20 volumio-miro systemd[1]: Started Network Time Service. May 22 16:29:20 volumio-miro ntpd[1311]: proto: precision = 0.583 usec (-21) May 22 16:29:20 volumio-miro ntpd[1311]: Listen and drop on 0 v6wildcard [::]:123 May 22 16:29:20 volumio-miro ntpd[1311]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 22 16:29:20 volumio-miro ntpd[1311]: Listen normally on 2 lo 127.0.0.1:123 May 22 16:29:20 volumio-miro ntpd[1311]: Listen normally on 3 eth0 192.168.68.108:123 May 22 16:29:20 volumio-miro ntpd[1311]: Listen normally on 4 wlan0 192.168.68.116:123 May 22 16:29:20 volumio-miro ntpd[1311]: Listening on routing socket on fd #21 for interface updates May 22 16:29:20 volumio-miro ntpd[1311]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 16:29:20 volumio-miro ntpd[1311]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 16:29:21 volumio-miro systemd[1]: Started Samba SMB Daemon. May 22 16:29:21 volumio-miro smbd[1263]: [2025/05/22 16:29:21.051645, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 16:29:21 volumio-miro smbd[1263]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 22 16:29:21 volumio-miro wireless.js[546]: trying... May 22 16:29:21 volumio-miro sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 22 16:29:21 volumio-miro sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:21 volumio-miro sudo[1321]: pam_unix(sudo:session): session closed for user root May 22 16:29:21 volumio-miro wireless.js[546]: Connected to: ----miro1 May 22 16:29:21 volumio-miro wireless.js[546]: ---- May 22 16:29:21 volumio-miro sudo[1331]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:29:21 volumio-miro sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:21 volumio-miro sudo[1331]: pam_unix(sudo:session): session closed for user root May 22 16:29:21 volumio-miro wireless.js[546]: ... joined AP, wlan0 IPv4 is 192.168.68.116, ipV6 is undefined May 22 16:29:21 volumio-miro wireless.js[546]: It's done! AP May 22 16:29:21 volumio-miro iwconfig[1342]: Error for wireless request "Set Power Management" (8B2C) : May 22 16:29:21 volumio-miro iwconfig[1342]: SET failed on device wlan0 ; Operation not permitted. May 22 16:29:21 volumio-miro systemd[1]: Started Wireless Services. May 22 16:29:21 volumio-miro systemd[1]: Started Volumio Backend Module. May 22 16:29:21 volumio-miro systemd[1]: Started Volumio Cpu Tweaker. May 22 16:29:21 volumio-miro systemd[1]: Started Start Volumio Kiosk. May 22 16:29:21 volumio-miro systemd[1]: Reached target Multi-User System. May 22 16:29:21 volumio-miro systemd[1]: Reached target Graphical Interface. May 22 16:29:21 volumio-miro systemd[1]: Starting Update UTMP about System Runlevel Changes... May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Starting Volumio Kiosk with arguments for cursor display May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: Setting RT Priority for mpd May 22 16:29:21 volumio-miro systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 22 16:29:21 volumio-miro systemd[1]: Started Update UTMP about System Runlevel Changes. May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: Setting MPD Affinity May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: pid 829's current affinity mask: f May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: pid 829's new affinity mask: 3 May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 22 16:29:21 volumio-miro volumio-cpu-tweak[1344]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 22 16:29:21 volumio-miro systemd[1]: volumio_cpu_tweak.service: Succeeded. May 22 16:29:21 volumio-miro startkiosk.sh[1345]: X.Org X Server 1.20.4 May 22 16:29:21 volumio-miro startkiosk.sh[1345]: X Protocol Version 11, Revision 0 May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Current Operating System: Linux volumio-miro 4.4.71+ #23 SMP Sun Sep 20 12:23:26 CEST 2020 armv7l May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Kernel command line: earlyprintk quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear console=tty1 console=ttyS3,115200n8 rw init=/sbin/init imgpart=UUID=a32bdd9f-4394-4094-acb5-0caaf5cc0c55 imgfile=/volumio_current.sqsh bootpart=UUID=0A1E-49F8 datapart=UUID=e2476203-9503-4ba1-9824-4ed6b229d5aa uuidconfig=/extlinux/extlinux.conf vt.global_cursor_default=0 bootdelay=5 uboot_version=2017.07-g879646d1d6 root=/dev/mmcblk1p2 May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Build Date: 18 December 2021 09:05:36AM May 22 16:29:21 volumio-miro startkiosk.sh[1345]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Current version of pixman: 0.36.0 May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Before reporting problems, check http://wiki.x.org May 22 16:29:21 volumio-miro startkiosk.sh[1345]: to make sure that you have the latest version. May 22 16:29:21 volumio-miro startkiosk.sh[1345]: Markers: (--) probed, (**) from config file, (==) default setting, May 22 16:29:21 volumio-miro startkiosk.sh[1345]: (++) from command line, (!!) notice, (II) informational, May 22 16:29:21 volumio-miro startkiosk.sh[1345]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 22 16:29:21 volumio-miro startkiosk.sh[1345]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 22 16:29:21 2025 May 22 16:29:21 volumio-miro startkiosk.sh[1345]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 22 16:29:21 volumio-miro startkiosk.sh[1345]: gbm: failed to open any driver (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) May 22 16:29:21 volumio-miro startkiosk.sh[1345]: gbm: Last dlopen error: /usr/lib/dri/rockchip_dri.so: cannot open shared object file: No such file or directory May 22 16:29:21 volumio-miro startkiosk.sh[1345]: failed to load driver: rockchip May 22 16:29:21 volumio-miro volumio-remote-updater[555]: [2025-05-22 16:29:21] [info] asio async_connect error: system:111 (Connection refused) May 22 16:29:21 volumio-miro volumio-remote-updater[555]: [2025-05-22 16:29:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 22 16:29:21 volumio-miro volumio-remote-updater[555]: [2025-05-22 16:29:21] [error] handle_connect error: Underlying Transport Error May 22 16:29:21 volumio-miro systemd[1]: apt-daily-upgrade.service: Succeeded. May 22 16:29:21 volumio-miro systemd[1]: Started Daily apt upgrade and clean activities. May 22 16:29:21 volumio-miro systemd[1]: Startup finished in 15.665s (kernel) + 16.070s (userspace) = 31.735s. May 22 16:29:22 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:29:22 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:29:22 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:29:22 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:29:22 volumio-miro startkiosk.sh[1345]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 22 16:29:23 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:23 volumio-miro volumio[1343]: info: ----- Volumio3 ---- May 22 16:29:23 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:23 volumio-miro volumio[1343]: info: ----- System startup ---- May 22 16:29:23 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:24 volumio-miro volumio[1343]: info: MYVOLUMIO Environment detected May 22 16:29:24 volumio-miro volumio[1343]: info: Plugin folders cleanup May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning into folder /volumio/app/plugins/ May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category audio_interface May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category miscellanea May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category music_service May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category plugins.json May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category system_controller May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category user_interface May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning into folder /data/plugins/ May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category audio_interface May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category music_service May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category system_controller May 22 16:29:24 volumio-miro volumio[1343]: info: Scanning category system_hardware May 22 16:29:24 volumio-miro volumio[1343]: info: Plugin folders cleanup completed May 22 16:29:24 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:24 volumio-miro volumio[1343]: info: ----- Core plugins startup ---- May 22 16:29:24 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:24 volumio-miro volumio[1343]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:29:24 volumio-miro volumio[1343]: info: Adding plugin upnp to MyMusic Plugins May 22 16:29:24 volumio-miro volumio[1343]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:29:24 volumio-miro volumio[1343]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:29:24 volumio-miro volumio[1343]: info: Loading plugins from folder /data/plugins/ May 22 16:29:24 volumio-miro volumio[1343]: info: Loading plugin "system"... May 22 16:29:24 volumio-miro volumio[1343]: info: Loading plugin "ampswitch"... May 22 16:29:24 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin ampswitch May 22 16:29:24 volumio-miro volumio[1343]: info: Loading plugin "appearance"... May 22 16:29:25 volumio-miro volumio[1343]: info: Loading plugin "network"... May 22 16:29:25 volumio-miro volumio[1343]: info: Refreshing Cached IP Addresses May 22 16:29:25 volumio-miro sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:29:25 volumio-miro volumio[1343]: info: Loading plugin "services"... May 22 16:29:25 volumio-miro sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:29:25 volumio-miro volumio[1343]: info: Loading plugin "alsa_controller"... May 22 16:29:25 volumio-miro sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:25 volumio-miro sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:29:25 volumio-miro sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:25 volumio-miro sudo[1489]: pam_unix(sudo:session): session closed for user root May 22 16:29:25 volumio-miro sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:25 volumio-miro sudo[1491]: pam_unix(sudo:session): session closed for user root May 22 16:29:25 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:29:25 volumio-miro volumio[1343]: info: Loading plugin "wizard"... May 22 16:29:25 volumio-miro volumio[1343]: info: Loading plugin "networkfs"... May 22 16:29:25 volumio-miro volumio[1343]: info: Starting Udev Watcher for removable devices May 22 16:29:26 volumio-miro volumio[1343]: info: Ignoring mount for partition: BOOT May 22 16:29:26 volumio-miro volumio[1343]: info: Ignoring mount for partition: volumio May 22 16:29:26 volumio-miro volumio[1343]: info: Ignoring mount for partition: volumio_data May 22 16:29:26 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "volumio_command_line_client"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "upnp"... May 22 16:29:26 volumio-miro volumio[1343]: info: [1747924166023] Starting Upmpd Daemon May 22 16:29:26 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "my_music"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "mpd"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "upnp_browser"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "alarm-clock"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "airplay_emulation"... May 22 16:29:26 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "last_100"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "webradio"... May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "i2s_dacs"... May 22 16:29:26 volumio-miro volumio[1343]: info: I2S DAC not set, start Auto-detection May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "volumiodiscovery"... May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** For more information see May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:29:26 volumio-miro volumio[1343]: *** WARNING *** For more information see May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** For more information see May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:29:26 volumio-miro node[1343]: *** WARNING *** For more information see May 22 16:29:26 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:29:26 volumio-miro volumio[1343]: info: Discovery: Started advertising with name: Volumio Miro May 22 16:29:26 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:29:26 volumio-miro volumio[1343]: info: Loading plugin "spop"... May 22 16:29:27 volumio-miro sudo[1501]: pam_unix(sudo:session): session closed for user root May 22 16:29:27 volumio-miro volumio[1343]: info: Loading plugin "outputs"... May 22 16:29:27 volumio-miro volumio[1343]: info: Loading plugin "albumart"... May 22 16:29:27 volumio-miro volumio[1343]: info: Plugin example_plugin is not enabled May 22 16:29:27 volumio-miro volumio[1343]: info: Loading plugin "inputs"... May 22 16:29:27 volumio-miro volumio[1343]: info: Loading plugin "updater_comm"... May 22 16:29:28 volumio-miro volumio[1343]: info: Plugin mpdemulation is not enabled May 22 16:29:28 volumio-miro volumio[1343]: info: Loading plugin "rest_api"... May 22 16:29:28 volumio-miro volumio[1343]: info: Loading plugin "websocket"... May 22 16:29:28 volumio-miro volumio[1343]: info: Starting Socket.io Server version 2.3.0 May 22 16:29:28 volumio-miro volumio[1343]: info: Loading plugin "fusiondsp"... May 22 16:29:28 volumio-miro volumio[1343]: Forking 3 albumart workers May 22 16:29:28 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin fusiondsp May 22 16:29:29 volumio-miro volumio[1343]: info: Loading plugin "audiophonics_evo_sabre"... May 22 16:29:29 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 22 16:29:29 volumio-miro volumio[1343]: Starting albumart workers May 22 16:29:29 volumio-miro volumio[1343]: info: Loading plugin "onkyo_control"... May 22 16:29:29 volumio-miro volumio[1343]: Starting albumart workers May 22 16:29:29 volumio-miro volumio[1343]: Starting albumart workers May 22 16:29:30 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin onkyo_control May 22 16:29:30 volumio-miro volumio[1343]: info: Loading i18n strings for locale pl May 22 16:29:30 volumio-miro volumio[1343]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. May 22 16:29:30 volumio-miro volumio[1343]: Updating browse sources language May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::initPlayerControls May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: Express server listening on port 3000 May 22 16:29:30 volumio-miro volumio[1343]: [Metrics] WebUI: 7s 483.24ms May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::resetVolumioState May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::getcurrentVolume May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::pushState May 22 16:29:30 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioPushState May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:30 volumio-miro volumio[1343]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 22 16:29:30 volumio-miro volumio[1343]: info: Completed loading Core Plugins May 22 16:29:30 volumio-miro volumio[1343]: info: Preparing to generate the ALSA configuration file May 22 16:29:30 volumio-miro volumio[1343]: info: Volumio Network Manager: Network status updated: 3 May 22 16:29:30 volumio-miro volumio[1343]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 16:29:30 volumio-miro volumio[1343]: info: Reading ALSA contributions from plugins. May 22 16:29:30 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 22 16:29:30 volumio-miro volumio[1343]: info: Reloading queue from file May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::setRepeat null single undefined May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::pushState May 22 16:29:30 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioPushState May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::setRandom null May 22 16:29:30 volumio-miro volumio[1343]: info: CoreStateMachine::pushState May 22 16:29:30 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:30 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioPushState May 22 16:29:30 volumio-miro volumio[1343]: info: Setting Device type: Tinkerboard May 22 16:29:31 volumio-miro volumio[1343]: info: Discovery: adding beeb6fb6-e4a3-4bbf-b656-f0a88814bb80 May 22 16:29:31 volumio-miro volumio[1343]: info: Discovery: Found device Volumio Miro May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:31 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:31 volumio-miro volumio[1343]: info: Discovery: this is already registered, beeb6fb6-e4a3-4bbf-b656-f0a88814bb80 May 22 16:29:31 volumio-miro volumio[1343]: info: Discovery: Found device Volumio Miro May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:31 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:31 volumio-miro volumio[1343]: info: Asound.conf file unchanged, so no further update is needed May 22 16:29:31 volumio-miro volumio[1343]: info: Output device has changed, restarting MPD May 22 16:29:31 volumio-miro volumio[1343]: info: Output device has changed, restarting Shairport Sync May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:31 volumio-miro sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:29:31 volumio-miro sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:29:31 volumio-miro sudo[1632]: pam_unix(sudo:session): session closed for user root May 22 16:29:31 volumio-miro sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro systemd[1]: Stopping Music Player Daemon... May 22 16:29:31 volumio-miro volumio[1343]: info: ___________ START PLUGINS ___________ May 22 16:29:31 volumio-miro volumio[1343]: error: Plugin ampswitch failed to start! Error: EINVAL: invalid argument, write May 22 16:29:31 volumio-miro kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted May 22 16:29:31 volumio-miro volumio[1343]: info: ControllerMpd::onStart: Initializing MPD May 22 16:29:31 volumio-miro volumio[1343]: info: Creating MPD Configuration file May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:29:31 volumio-miro volumio[1343]: info: [1747924171225] CoreMusicLibrary::Adding element Serwery Mediów May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:31 volumio-miro sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:31 volumio-miro sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:29:31 volumio-miro sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:31 volumio-miro sudo[1665]: pam_unix(sudo:session): session closed for user root May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:29:31 volumio-miro volumio[1343]: info: [1747924171325] CoreMusicLibrary::Adding element Last_100 May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:31 volumio-miro systemd[1]: mpd.service: Succeeded. May 22 16:29:31 volumio-miro systemd[1]: Stopped Music Player Daemon. May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:29:31 volumio-miro volumio[1343]: info: [1747924171328] CoreMusicLibrary::Adding element Webradio May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:31 volumio-miro systemd[1]: Starting Music Player Daemon... May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:29:31 volumio-miro volumio[1343]: info: Initializing BBC Radios May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: Creating Spotify config file May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro sudo[1687]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:29:31 volumio-miro sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro sudo[1687]: pam_unix(sudo:session): session closed for user root May 22 16:29:31 volumio-miro volumio[1343]: info: Loading i18n strings for locale pl May 22 16:29:31 volumio-miro volumio[1343]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: FusionDsp - mixtype--------------------- None May 22 16:29:31 volumio-miro volumio[1343]: info: EVO SABRE : Starting Plugin May 22 16:29:31 volumio-miro volumio[1343]: info: Loading i18n strings for locale pl May 22 16:29:31 volumio-miro volumio[1343]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. May 22 16:29:31 volumio-miro volumio[1343]: info: Volumio Calling Home May 22 16:29:31 volumio-miro volumio[1343]: info: Preparing to generate the ALSA configuration file May 22 16:29:31 volumio-miro volumio[1343]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 16:29:31 volumio-miro volumio[1343]: info: Reading ALSA contributions from plugins. May 22 16:29:31 volumio-miro volumio[1343]: info: MPD Permissions set May 22 16:29:31 volumio-miro volumio[1343]: info: Volumio called home May 22 16:29:31 volumio-miro volumio[1343]: info: Spotify config file written May 22 16:29:31 volumio-miro sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 22 16:29:31 volumio-miro volumio[1343]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STARTED ******** May 22 16:29:31 volumio-miro sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 22 16:29:31 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:29:31 volumio-miro sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:31 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro systemd[1]: Started go-librespot Daemon. May 22 16:29:31 volumio-miro sudo[1718]: pam_unix(sudo:session): session closed for user root May 22 16:29:31 volumio-miro go-librespot[1728]: go-librespot daemon starting... May 22 16:29:31 volumio-miro systemd[1]: Reloading. May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:31 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=info msg="running go-librespot 0.2.0" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="app state loaded" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 22 16:29:32 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:32 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 16:29:32 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:32 volumio-miro volumio[1343]: info: No need to fix Spotify hosts May 22 16:29:32 volumio-miro volumio-remote-updater[555]: [2025-05-22 16:29:32] [connect] Successful connection May 22 16:29:32 volumio-miro 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. May 22 16:29:32 volumio-miro volumio-remote-updater[555]: [2025-05-22 16:29:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747924172 101 May 22 16:29:32 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 May 22 16:29:32 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:32 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:32 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 22 16:29:32 volumio-miro sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 16:29:32 volumio-miro systemd[1]: serial-getty@ttyS3.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=info msg="zeroconf server listening on port 33636" May 22 16:29:32 volumio-miro sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 16:29:32 volumio-miro sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:32 volumio-miro sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:32 volumio-miro sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 16:29:32 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:32 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:32 volumio-miro sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="obtained new client token: AABCKSqR8FxaITL/jNMN4mSUObpUiIXjKq606GYTM/Dxvyw3BcqwyCKJyCbr5JEKxn49vWquDNf4ffxEGuKWDFVN0jrSZ6U/lGZLWIYItSzWHg6DcELV7zV68m6FUCgPAD2AsXEu7pP6nAQ1LNVIBPeP4huKbVyVZUVV6g/QLBrH5k+wQZWrVOT7EY56ThAs3q19yPaYW9HH6LePzHh5TNC5orhmOdk1MFsWOVeq3jrwh0ZoXmz6FUY=" May 22 16:29:32 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:32 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:32 volumio-miro volumio[1343]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 22 16:29:32 volumio-miro volumio[1343]: SPOTIFY: BQA46KwUjOdowENP6YOPrX4hZ-m837SPMc_-TkVoSTB74agmxVV9EhD-mnZzrg3GwOij8JNgx_KL6QHQcZg3xe6eF6zZjYgByjjpI_c2wC_7HSeDWQNrMLLZZrXeSXIF13RnXSYmdtf2_1i61NDXdK5NS_DYZkOkX-Rb9rUqsY8dajs00EvfpWZSg5wJKOMLm6MP_EIZ3u4jyCsqA9fU4y2vWtm4Ri5eEWhPdro5PCziT9gkN40GGedUfpz1_UucvT6C8RYNGkIV7fGEJdNK764xaHzrdAjzmN1N3JF8yb_5Wzk May 22 16:29:32 volumio-miro volumio[1343]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 22 16:29:32 volumio-miro volumio[1343]: info: New Spotify access token = BQA46KwUjOdowENP6YOPrX4hZ-m837SPMc_-TkVoSTB74agmxVV9EhD-mnZzrg3GwOij8JNgx_KL6QHQcZg3xe6eF6zZjYgByjjpI_c2wC_7HSeDWQNrMLLZZrXeSXIF13RnXSYmdtf2_1i61NDXdK5NS_DYZkOkX-Rb9rUqsY8dajs00EvfpWZSg5wJKOMLm6MP_EIZ3u4jyCsqA9fU4y2vWtm4Ri5eEWhPdro5PCziT9gkN40GGedUfpz1_UucvT6C8RYNGkIV7fGEJdNK764xaHzrdAjzmN1N3JF8yb_5Wzk May 22 16:29:32 volumio-miro volumio[1343]: info: Spotify credentials grant success - running version from March 24, 2019 May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="completed keyexchange" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=debug msg="completed challenge" May 22 16:29:32 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:32+02:00" level=info msg="authenticated AP as 217a5xjhew5fxodltwy7iemki" May 22 16:29:32 volumio-miro volumio[1343]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"Aries","artist":"Alejandro De Pinedo","album":"Zodiac","albumart":"https://i.scdn.co/image/ab67616d0000b273945ca08ec54b6c471d259faa","uri":"spotify:track:2LIHxCYJteBphA7orZpQpP","trackType":"spotify","codec":"ogg","seek":0,"duration":278,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.105","model":"TX-RZ3400"} May 22 16:29:32 volumio-miro volumio[1343]: info: Asound.conf file unchanged, so no further update is needed May 22 16:29:32 volumio-miro volumio[1343]: info: Output device has changed, restarting MPD May 22 16:29:32 volumio-miro sudo[1715]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=info msg="authenticated Login5 as 217a5xjhew5fxodltwy7iemki" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="initializing zeroconf session, username: 217a5xjhew5fxodltwy7iemki" May 22 16:29:33 volumio-miro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 16:29:33 volumio-miro systemd[1]: shairport-sync.service: Succeeded. May 22 16:29:33 volumio-miro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 16:29:33 volumio-miro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 16:29:33 volumio-miro sudo[1790]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro sudo[1787]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro sudo[1789]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro volumio[1343]: info: Output device has changed, restarting Shairport Sync May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="dealer connection opened" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=trace msg="starting accesspoint recv loop" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=trace msg="starting dealer recv loop" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=trace msg="received accesspoint ping" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="received connection id: OTFmNTExYmItZjBiZC00NWFkLThmMTctMGIwNTUxMTM2MjYzK2RlYWxlcit0Y3A6Ly8wYWIxNTAwNi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMDE2RjhBRjdCMTlFQTdBRUZGQkU0RkFFMEIyRTQyNkRGQTE1NjBFNjZCMTIyNjM5MDM4MENDRDU3REIxMjlFOA==" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=trace msg="received accesspoint pong ack" May 22 16:29:33 volumio-miro sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:29:33 volumio-miro sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:29:33 volumio-miro volumio[1343]: info: EVO SABRE : systemd cmd daemon-reload : success May 22 16:29:33 volumio-miro sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro mpd[1708]: May 22 16:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:29:33 volumio-miro systemd[1]: Started Music Player Daemon. May 22 16:29:33 volumio-miro sudo[1634]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="put connect state because NEW_DEVICE" May 22 16:29:33 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:33+02:00" level=debug msg="update volume to 65535/65535" May 22 16:29:33 volumio-miro sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro sudo[1667]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro sudo[1823]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro systemd[1]: Stopping Music Player Daemon... May 22 16:29:33 volumio-miro volumio[1343]: info: Shairport-Sync Started May 22 16:29:33 volumio-miro volumio[1343]: Error adding Membership: Error: addMembership EINVAL May 22 16:29:33 volumio-miro volumio[1343]: info: Shairport-Sync Started May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 16:29:33 volumio-miro sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 22 16:29:33 volumio-miro systemd[1]: mpd.service: Succeeded. May 22 16:29:33 volumio-miro systemd[1]: Stopped Music Player Daemon. May 22 16:29:33 volumio-miro sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro systemd[1]: Starting Music Player Daemon... May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro systemd[1]: Started OLED Display Service for EVO SABRE. May 22 16:29:33 volumio-miro sudo[1860]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro volumio[1343]: info: MPD Permissions set May 22 16:29:33 volumio-miro sudo[1896]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 22 16:29:33 volumio-miro sudo[1893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:29:33 volumio-miro sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro volumio[1343]: info: camilladsp service started and running in background, instance 1 May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:33 volumio-miro sudo[1893]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 176400 192000 <-AAAAAAAAAAAAA May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp loaded May 22 16:29:33 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp - Reporting Fusion DSP Enabled May 22 16:29:33 volumio-miro volumio[1343]: info: Adding Signal Path Element [object Object] May 22 16:29:33 volumio-miro volumio[1343]: info: Adding fusiondspeq DSP Signal Path Element May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp - ---- installed callbackRead May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 22 16:29:33 volumio-miro sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 22 16:29:33 volumio-miro sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro volumio[1343]: error: Cannot initialize MPD Connection: MPD is not running May 22 16:29:33 volumio-miro volumio[1343]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 22 16:29:33 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:33 volumio-miro systemd[1]: Started FusionDsp Daemon. May 22 16:29:33 volumio-miro sudo[1945]: pam_unix(sudo:session): session closed for user root May 22 16:29:33 volumio-miro sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 22 16:29:33 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 16:29:33 volumio-miro volumio[1343]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 22 16:29:33 volumio-miro sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:33 volumio-miro sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 16:29:34 volumio-miro sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:34 volumio-miro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 16:29:34 volumio-miro systemd[1]: Starting lircd(8) initialization helper tool... May 22 16:29:34 volumio-miro volumio[1343]: SPOTIFY: User informations: {"country":"PL","display_name":"Mirek Piechota","email":"janstarzec@interia.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/217a5xjhew5fxodltwy7iemki"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/217a5xjhew5fxodltwy7iemki","id":"217a5xjhew5fxodltwy7iemki","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1004010236378080&height=300&width=300&ext=1750489523&hash=AT_VnMuOOsXTuCycuwzWg_MV","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1004010236378080&height=50&width=50&ext=1750489523&hash=AT9p4yMhdt-ChhwwJtgfQpB_","width":64}],"product":"premium","type":"user","uri":"spotify:user:217a5xjhew5fxodltwy7iemki"} May 22 16:29:34 volumio-miro systemd[1]: shairport-sync.service: Succeeded. May 22 16:29:34 volumio-miro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 16:29:34 volumio-miro volumio[1343]: info: Spotify Successfully logged in May 22 16:29:34 volumio-miro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 16:29:34 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:29:34 volumio-miro sudo[1965]: pam_unix(sudo:session): session closed for user root May 22 16:29:34 volumio-miro volumio[1343]: info: [1747924174113] CoreMusicLibrary::Adding element Spotify May 22 16:29:34 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:34 volumio-miro volumio[1343]: Cannot find translation for source Spotify May 22 16:29:34 volumio-miro volumio[1343]: info: Shairport-Sync Started May 22 16:29:34 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:34+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 22 16:29:34 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:34+02:00" level=trace msg="emitting websocket event: volume" May 22 16:29:34 volumio-miro lircd-setup[1976]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 22 16:29:34 volumio-miro lircd-setup[1976]: parser = configparser.SafeConfigParser() May 22 16:29:34 volumio-miro systemd[1]: lircd-setup.service: Succeeded. May 22 16:29:34 volumio-miro systemd[1]: Started lircd(8) initialization helper tool. May 22 16:29:34 volumio-miro systemd[1]: Started evo_remote.service. May 22 16:29:34 volumio-miro sudo[1957]: pam_unix(sudo:session): session closed for user root May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: lircd: Opening log, level: Info May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Version: lircd 0.10.1 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: System info: Linux volumio-miro 4.4.71+ #23 SMP Sun Sep 20 12:23:26 CEST 2020 armv7l GNU/Linux May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: Initial device: /dev/lirc0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Warning: Cannot access device: /dev/lirc0 May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Info: lircd: Opening log, level: Info May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Warning: Running as root May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: Initial device: /dev/lirc0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Warning: Cannot access device: /dev/lirc0 May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Info: Using remote: ApEvo. May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: driver: default May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: output: /var/run/lirc/lircd May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: nodaemon: 1 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: logfile: syslog May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: immediate-init: 0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: permission: 666 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: driver-options: May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: listen: 0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: connect: (null) May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: userelease: 0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: effective_user: (null) May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: release_suffix: _EVUP May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: allow_simulate: 0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: repeat_max: 600 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Options: dynamic_codes: (null) May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Current driver: default May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Driver API version: 3 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Driver version: 0.10.0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: lircd: Opening log, level: Info May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Warning: Running as root May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: Using remote: ApEvo. May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 16:29:34 volumio-miro volumio[1343]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 22 16:29:34 volumio-miro sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 22 16:29:34 volumio-miro sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:34 volumio-miro systemd[1]: Starting lircd(8) initialization helper tool... May 22 16:29:34 volumio-miro sudo[1896]: pam_unix(sudo:session): session closed for user root May 22 16:29:34 volumio-miro lircd-setup[2006]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 22 16:29:34 volumio-miro lircd-setup[2006]: parser = configparser.SafeConfigParser() May 22 16:29:34 volumio-miro systemd[1]: evo_oled2.service: Main process exited, code=exited, status=1/FAILURE May 22 16:29:34 volumio-miro systemd[1]: evo_oled2.service: Failed with result 'exit-code'. May 22 16:29:34 volumio-miro systemd[1]: lircd-setup.service: Succeeded. May 22 16:29:34 volumio-miro systemd[1]: Started lircd(8) initialization helper tool. May 22 16:29:34 volumio-miro systemd[1]: Started evo_irexec.service. May 22 16:29:34 volumio-miro sudo[2000]: pam_unix(sudo:session): session closed for user root May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Notice: accepted new client on /var/run/lirc/lircd May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Notice: accepted new client on /var/run/lirc/lircd May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Info: Cannot configure the rc device for /dev/lirc0 May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:34 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Warning: Failed to initialize hardware May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Info: Cannot configure the rc device for /dev/lirc0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:34 volumio-miro lircd-0.10.1[1993]: Warning: Failed to initialize hardware May 22 16:29:35 volumio-miro volumio[1343]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 22 16:29:35 volumio-miro volumio[1343]: info: Completed starting Core Plugins May 22 16:29:35 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:35 volumio-miro volumio[1343]: info: ----- MyVolumio plugins startup ---- May 22 16:29:35 volumio-miro volumio[1343]: info: ------------------------------------------- May 22 16:29:35 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Fetching plans data.... May 22 16:29:35 volumio-miro mpd[1955]: May 22 16:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:29:35 volumio-miro systemd[1]: Started Music Player Daemon. May 22 16:29:35 volumio-miro sudo[1829]: pam_unix(sudo:session): session closed for user root May 22 16:29:35 volumio-miro volumio[1343]: info: go-librespot daemon successfully initialized May 22 16:29:35 volumio-miro volumio[1343]: error: MPD error: The expression evaluated to a falsy value: May 22 16:29:35 volumio-miro volumio[1343]: assert.ok(self.idling) May 22 16:29:35 volumio-miro volumio[1343]: error: The expression evaluated to a falsy value: May 22 16:29:35 volumio-miro volumio[1343]: assert.ok(self.idling) May 22 16:29:35 volumio-miro volumio[1343]: error: updateQueue error: null May 22 16:29:35 volumio-miro volumio[1343]: error: updateQueue error: null May 22 16:29:35 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:35 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:35 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:35 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:36 volumio-miro sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:29:36 volumio-miro sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:29:36 volumio-miro sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:36 volumio-miro sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:36 volumio-miro sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 22 16:29:36 volumio-miro sudo[2095]: pam_unix(sudo:session): session closed for user root May 22 16:29:36 volumio-miro sudo[2094]: pam_unix(sudo:session): session closed for user root May 22 16:29:36 volumio-miro sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:36 volumio-miro systemd[1]: Started UPnP Renderer front-end to MPD. May 22 16:29:36 volumio-miro sudo[2098]: pam_unix(sudo:session): session closed for user root May 22 16:29:36 volumio-miro volumio[1343]: info: Upmpdcli Daemon Started May 22 16:29:36 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:36 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:36 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:36 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:37 volumio-miro volumio[2120]: Generating RSA private key, 4096 bit long modulus (2 primes) May 22 16:29:37 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 1 May 22 16:29:37 volumio-miro volumio[2120]: .........++++ May 22 16:29:37 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:37 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:37 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:37 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:38 volumio-miro systemd[1]: systemd-fsckd.service: Succeeded. May 22 16:29:38 volumio-miro volumio[1343]: info: Initializing connection to go-librespot Websocket May 22 16:29:38 volumio-miro go-librespot[1728]: time="2025-05-22T16:29:38+02:00" level=debug msg="new websocket client" May 22 16:29:38 volumio-miro volumio[1343]: info: Connection to go-librespot Websocket established May 22 16:29:38 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:38 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:38 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:38 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:39 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:29:39 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:29:39 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:29:39 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:29:39 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:39 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:39 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:39 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:40 volumio-miro systemd[1]: systemd-hostnamed.service: Succeeded. May 22 16:29:40 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 May 22 16:29:40 volumio-miro volumio[1343]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 22 16:29:40 volumio-miro volumio[2120]: ..........................................++++ May 22 16:29:40 volumio-miro volumio[2120]: e is 65537 (0x010001) May 22 16:29:40 volumio-miro volumio[2120]: writing RSA key May 22 16:29:40 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:40 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:40 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:40 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetVisibleSources May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:41 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:29:41 volumio-miro volumio[1343]: info: Getting Spotify volume May 22 16:29:41 volumio-miro volumio[1343]: info: Spotify volume: 100 May 22 16:29:41 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 22 16:29:41 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:41 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:41 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:41 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:41 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:41 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:42 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 8 May 22 16:29:42 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:42 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:42 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:42 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:42 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:42 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:43 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 16:29:43 volumio-miro volumio[1343]: info: Listing playlists May 22 16:29:43 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:29:43 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:29:43 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:29:43 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin bluetooth to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin multiroom to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin metavolumio to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin cd_controller to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin smart_inputs to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: Adding plugin tidalconnect to MyMusic Plugins May 22 16:29:43 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 22 16:29:44 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:44 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:44 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:44 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:44 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 22 16:29:44 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 22 16:29:44 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:44 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:44 volumio-miro volumio[1343]: info: Starting MyVolumio Remote Streaming Endpoints May 22 16:29:44 volumio-miro volumio[1343]: info: MyVolumio login type: Token May 22 16:29:44 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 22 16:29:44 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 22 16:29:45 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:45 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:45 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:45 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:45 volumio-miro volumio[1343]: info: Starting Streaming Service Transparent Proxy May 22 16:29:46 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:46 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:46 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:46 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:46 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 22 16:29:46 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 22 16:29:46 volumio-miro volumio[1343]: info: Streaming services startup May 22 16:29:46 volumio-miro volumio[1343]: info: Starting Streaming Daemon May 22 16:29:46 volumio-miro sudo[2197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 16:29:46 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 22 16:29:46 volumio-miro sudo[2197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:46 volumio-miro sudo[2197]: pam_unix(sudo:session): session closed for user root May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:29:46 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:46 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:46 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:46 volumio-miro volumio[1343]: error: Cannot start Volumio Streaming Daemon May 22 16:29:46 volumio-miro volumio[1343]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 16:29:46 volumio-miro volumio[1343]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 16:29:46 volumio-miro volumio[1343]: error: MyVolumio Custom Token format not valid, refreshing it May 22 16:29:46 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 22 16:29:46 volumio-miro volumio[1343]: info: MyVolumio login type: Token May 22 16:29:46 volumio-miro volumio[1343]: STREAMING PROXY: Starting server on port 3245 May 22 16:29:46 volumio-miro volumio[1343]: Node JS runtime: 14 May 22 16:29:47 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:47 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:47 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:47 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:47 volumio-miro volumio[1343]: info: MyVolumio login type: Token May 22 16:29:47 volumio-miro volumio[1343]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 22 16:29:47 volumio-miro volumio[1343]: info: MyVolumio token set successfully May 22 16:29:47 volumio-miro volumio[1343]: info: MYVOLUMIO: Adding device May 22 16:29:47 volumio-miro volumio[1343]: info: MYVOLUMIO: Evaluating Server May 22 16:29:48 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:48 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:48 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:48 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:48 volumio-miro volumio[1343]: info: MyVolumio Plan changed: superstar May 22 16:29:48 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar May 22 16:29:48 volumio-miro volumio[1343]: info: Adding audio output: May 22 16:29:48 volumio-miro volumio[1343]: info: MYVOLUMIO: Adding device May 22 16:29:48 volumio-miro volumio[1343]: info: MYVOLUMIO: Evaluating Server May 22 16:29:48 volumio-miro volumio[1343]: info: Remote config written successfully May 22 16:29:48 volumio-miro volumio[1343]: info: Starting Tunnel 1 May 22 16:29:48 volumio-miro volumio[1343]: info: Starting Tunnel Connection Checker May 22 16:29:48 volumio-miro volumio[1343]: info: Completed starting MyVolumio Plugin May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:29:48 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:48 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:48 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:48 volumio-miro volumio[1343]: info: MYVolumio Device enabled May 22 16:29:48 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 22 16:29:48 volumio-miro volumio[1343]: info: MyVolumio status changed May 22 16:29:48 volumio-miro volumio[1343]: info: Streaming services startup May 22 16:29:48 volumio-miro volumio[1343]: info: Starting Streaming Daemon May 22 16:29:48 volumio-miro volumio[1343]: info: Setting Geolocation for MyVolumio to eu6 May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:48 volumio-miro sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 16:29:48 volumio-miro sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 22 16:29:48 volumio-miro volumio[1343]: error: [MyVolumio PluginManager] Cache data is invalid! May 22 16:29:48 volumio-miro sudo[2269]: pam_unix(sudo:session): session closed for user root May 22 16:29:48 volumio-miro volumio[1343]: error: Cannot start Volumio Streaming Daemon May 22 16:29:48 volumio-miro volumio[1343]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 16:29:48 volumio-miro volumio[1343]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 16:29:48 volumio-miro volumio[1343]: info: Setting Geolocation for MyVolumio to eu6 May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:48 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:49 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:49 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:49 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:49 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:49 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 22 16:29:49 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 22 16:29:49 volumio-miro volumio[1343]: info: [1747924189222] Starting BluetoothController May 22 16:29:49 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 22 16:29:49 volumio-miro systemd[1]: systemd-timedated.service: Succeeded. May 22 16:29:50 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:50 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:50 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:50 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 22 16:29:50 volumio-miro sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 22 16:29:50 volumio-miro sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2282]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: MultiRoom plugin initialized May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: STOPPING SNAPCLIENT May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: Snap server stop May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: STOPPING volumioStreaming May 22 16:29:50 volumio-miro sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 22 16:29:50 volumio-miro sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 22 16:29:50 volumio-miro sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 22 16:29:50 volumio-miro sudo[2305]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 22 16:29:50 volumio-miro sudo[2313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2308]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro sudo[2319]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [_device-info._tcp.local IN PTR VOLUMIO\032MIRO._device-info._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN TXT "model=MacSamba" ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN SRV 0 0 0 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [_smb._tcp.local IN PTR VOLUMIO\032MIRO._smb._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN SRV 0 0 445 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [_http._tcp.local IN PTR Volumio\032Miro._http._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN SRV 0 0 80 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 22 16:29:50 volumio-miro sudo[2313]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 22 16:29:50 volumio-miro volumio[1343]: info: Preparing to generate the ALSA configuration file May 22 16:29:50 volumio-miro volumio[1343]: info: Updating MyVolumio device info May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: Updating MyVolumio device info May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 22 16:29:50 volumio-miro volumio[1343]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 22 16:29:50 volumio-miro volumio[1343]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 16:29:50 volumio-miro volumio[1343]: info: Reading ALSA contributions from plugins. May 22 16:29:50 volumio-miro volumio[1343]: info: Successfully Added MyVolumio device May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:29:50 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:50 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:50 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:50 volumio-miro volumio[1343]: info: Successfully Added MyVolumio device May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: Removed streaming files May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: volumioStreaming STOPPED May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: SNAPSERVER STOPPED May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: SNAPCLIENT STOPPED May 22 16:29:50 volumio-miro volumio[1343]: info: Asound.conf file written May 22 16:29:50 volumio-miro sudo[2345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 22 16:29:50 volumio-miro sudo[2345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2345]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN TXT "model=MacSamba" ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN SRV 0 0 0 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [_smb._tcp.local IN PTR VOLUMIO\032MIRO._smb._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN SRV 0 0 445 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [_http._tcp.local IN PTR Volumio\032Miro._http._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro volumio[1343]: info: Output device has changed, restarting MPD May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN SRV 0 0 80 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:50 volumio-miro volumio[1343]: info: Output device has changed, restarting Shairport Sync May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:50 volumio-miro sudo[2357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:29:50 volumio-miro sudo[2360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:29:50 volumio-miro sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:50 volumio-miro sudo[2357]: pam_unix(sudo:session): session closed for user root May 22 16:29:50 volumio-miro volumio[1343]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:50 volumio-miro systemd[1]: Stopping Music Player Daemon... May 22 16:29:50 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 22 16:29:50 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:29:51 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:51 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:51 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:51 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 22 16:29:51 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output for this device May 22 16:29:51 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output May 22 16:29:51 volumio-miro volumio[1343]: info: Adding audio output: May 22 16:29:51 volumio-miro volumio[1343]: info: Adding audio output: May 22 16:29:51 volumio-miro sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Miro May 22 16:29:51 volumio-miro volumio[1343]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 22 16:29:51 volumio-miro systemd[1]: mpd.service: Succeeded. May 22 16:29:51 volumio-miro systemd[1]: Stopped Music Player Daemon. May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro systemd[1]: Starting Music Player Daemon... May 22 16:29:51 volumio-miro sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 22 16:29:51 volumio-miro volumio[1343]: info: Adding METAVOLUMIO REST API Endpoints May 22 16:29:51 volumio-miro volumio[1343]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 22 16:29:51 volumio-miro volumio[1343]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 22 16:29:51 volumio-miro volumio[1343]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 22 16:29:51 volumio-miro volumio[1343]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 22 16:29:51 volumio-miro volumio[1343]: info: Preparing CD Folders May 22 16:29:51 volumio-miro volumio[1343]: info: Adding CD REST API Endpoints May 22 16:29:51 volumio-miro volumio[1343]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 22 16:29:51 volumio-miro volumio[1343]: info: Starting UDEV Watcher for CD May 22 16:29:51 volumio-miro volumio[1343]: info: Detecting CD presence with UDEV May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 22 16:29:51 volumio-miro volumio[1343]: info: Adding inputs REST Endpoints May 22 16:29:51 volumio-miro volumio[1343]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 22 16:29:51 volumio-miro volumio[1343]: info: Scanning Audio Inputs May 22 16:29:51 volumio-miro volumio[1343]: info: Checking against Known Cards name May 22 16:29:51 volumio-miro volumio[1343]: info: Checking against Known Cards name May 22 16:29:51 volumio-miro sudo[2385]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:29:51 volumio-miro volumio[1343]: info: Checking against Known Cards name May 22 16:29:51 volumio-miro volumio[1343]: info: Adding Server instance for streaming May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 22 16:29:51 volumio-miro volumio[1343]: error: Hi Res Audio Failed Login: Missing Login Data May 22 16:29:51 volumio-miro volumio[1343]: info: Adding HIGHRESAUDIO REST API Endpoints May 22 16:29:51 volumio-miro volumio[1343]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 22 16:29:51 volumio-miro sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:51 volumio-miro volumio[1343]: info: Refreshing TIDAL token May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 22 16:29:51 volumio-miro volumio[1343]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 22 16:29:51 volumio-miro sudo[2385]: pam_unix(sudo:session): session closed for user root May 22 16:29:51 volumio-miro sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 22 16:29:51 volumio-miro volumio[1343]: info: Stopping AccessToken refresher cron for QOBUZ May 22 16:29:51 volumio-miro sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:51 volumio-miro volumio[1343]: info: AccessToken refresher cron started for QOBUZ May 22 16:29:51 volumio-miro sudo[2400]: pam_unix(sudo:session): session closed for user root May 22 16:29:51 volumio-miro volumio[1343]: info: Adding QOBUZ REST API Endpoints May 22 16:29:51 volumio-miro volumio[1343]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 22 16:29:51 volumio-miro volumio[1343]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 22 16:29:51 volumio-miro volumio[1343]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 22 16:29:51 volumio-miro volumio[1343]: info: MPD Permissions set May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 16:29:51 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:51 volumio-miro sudo[2380]: pam_unix(sudo:session): session closed for user root May 22 16:29:51 volumio-miro volumio[1343]: info: Starting Shairport Sync May 22 16:29:51 volumio-miro volumio[1343]: info: Bluetooth name changed to Volumio Miro May 22 16:29:51 volumio-miro sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 16:29:51 volumio-miro volumio[1343]: info: MRS: Found cast device: Onkyo-TX-RZ3400-182e0856e2764fe8aa07c3598a981577 May 22 16:29:51 volumio-miro volumio[1343]: info: Adding audio output: May 22 16:29:51 volumio-miro sudo[2424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:51 volumio-miro volumio[1343]: info: MRS: Found cast device: Onkyo-TX-RZ3400-182e0856e2764fe8aa07c3598a981577 May 22 16:29:51 volumio-miro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 16:29:51 volumio-miro systemd[1]: shairport-sync.service: Succeeded. May 22 16:29:51 volumio-miro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 16:29:51 volumio-miro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 16:29:51 volumio-miro sudo[2424]: pam_unix(sudo:session): session closed for user root May 22 16:29:51 volumio-miro volumio[1343]: info: Shairport-Sync Started May 22 16:29:51 volumio-miro volumio[1343]: info: Successfully Updated MyVolumio device May 22 16:29:51 volumio-miro volumio[1343]: info: Access Token successfully retrieved May 22 16:29:51 volumio-miro volumio[1343]: info: Successfully Updated MyVolumio device May 22 16:29:52 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:52 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:52 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:52 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:52 volumio-miro volumio[1343]: info: Successfully retrieved User Session From TIDAL May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN SRV 0 0 80 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro mpd[2408]: May 22 16:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:29:52 volumio-miro volumio[1343]: info: Successfully retrieved User Subscription From TIDAL May 22 16:29:52 volumio-miro volumio[1343]: info: Adding TIDAL to Browse Sources May 22 16:29:52 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:29:52 volumio-miro volumio[1343]: info: [1747924192205] CoreMusicLibrary::Adding element TIDAL May 22 16:29:52 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:52 volumio-miro volumio[1343]: Cannot find translation for source Spotify May 22 16:29:52 volumio-miro volumio[1343]: Cannot find translation for source TIDAL May 22 16:29:52 volumio-miro sudo[2360]: pam_unix(sudo:session): session closed for user root May 22 16:29:52 volumio-miro systemd[1]: Started Music Player Daemon. May 22 16:29:52 volumio-miro volumio[1343]: info: Stopping AccessToken refresher cron May 22 16:29:52 volumio-miro volumio[1343]: info: AccessToken refresher cron started May 22 16:29:52 volumio-miro volumio[1343]: info: Adding TIDAL REST API Endpoints May 22 16:29:52 volumio-miro volumio[1343]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 22 16:29:52 volumio-miro volumio[1343]: error: updateQueue error: null May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN TXT "model=MacSamba" ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._device-info._tcp.local IN SRV 0 0 0 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [_smb._tcp.local IN PTR VOLUMIO\032MIRO._smb._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [VOLUMIO\032MIRO._smb._tcp.local IN SRV 0 0 445 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [_http._tcp.local IN PTR Volumio\032Miro._http._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro avahi-daemon[560]: Record [Volumio\032Miro._http._tcp.local IN SRV 0 0 80 volumio-miro.local ; ttl=120] not fitting in legacy unicast packet, dropping. May 22 16:29:52 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.108:3000 from 192.168.68.106 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 22 16:29:53 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:53 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:53 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:53 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:53 volumio-miro sudo[2447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 22 16:29:53 volumio-miro sudo[2447]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:53 volumio-miro systemd[1]: Started MyVolumio SSH Tunnel. May 22 16:29:53 volumio-miro sudo[2447]: pam_unix(sudo:session): session closed for user root May 22 16:29:53 volumio-miro autossh[2455]: port set to 0, monitoring disabled May 22 16:29:53 volumio-miro autossh[2455]: starting ssh (count 1) May 22 16:29:53 volumio-miro autossh[2455]: ssh child pid is 2460 May 22 16:29:53 volumio-miro volumio[1343]: info: Remote SSH Started May 22 16:29:53 volumio-miro volumiossh-tunnel[2453]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. May 22 16:29:53 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 22 16:29:53 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:53 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:54 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:54 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:54 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:54 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:54 volumio-miro sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 22 16:29:54 volumio-miro sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:54 volumio-miro systemd[1]: Started Volumio Bluetooth Module. May 22 16:29:54 volumio-miro sudo[2465]: pam_unix(sudo:session): session closed for user root May 22 16:29:54 volumio-miro sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 22 16:29:54 volumio-miro volumio[1343]: info: Volumio BT Module successfully started May 22 16:29:54 volumio-miro sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:54 volumio-miro volumiobt[2471]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 22 16:29:54 volumio-miro sudo[2473]: pam_unix(sudo:session): session closed for user root May 22 16:29:54 volumio-miro volumio[1343]: info: TidalConnect service stoped! May 22 16:29:54 volumio-miro volumio[1343]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 22 16:29:54 volumio-miro volumio[1343]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 22 16:29:54 volumio-miro sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 22 16:29:54 volumio-miro sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:54 volumio-miro systemd[1]: Started Volumio Tidal Connect Service. May 22 16:29:54 volumio-miro sudo[2494]: pam_unix(sudo:session): session closed for user root May 22 16:29:54 volumio-miro volumio[1343]: info: Executing endpoint tc_getconfig May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 22 16:29:54 volumio-miro vtcs[2500]: STARTING TidalConnect services, version: 1.4.0.34 May 22 16:29:54 volumio-miro vtcs[2500]: STARTED TidalConnect services. May 22 16:29:54 volumio-miro volumio[1343]: info: Executing endpoint tc_connect May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 22 16:29:54 volumio-miro volumio[1343]: info: Connecting to TidalConnect May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::servicePushState May 22 16:29:54 volumio-miro volumio[1343]: info: CoreStateMachine::pushState May 22 16:29:54 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioPushState May 22 16:29:54 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output update for this device May 22 16:29:54 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output May 22 16:29:54 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:54 volumio-miro volumio[1343]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::servicePushState May 22 16:29:54 volumio-miro volumio[1343]: info: CoreStateMachine::pushState May 22 16:29:54 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:54 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioPushState May 22 16:29:54 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output update for this device May 22 16:29:54 volumio-miro volumio[1343]: info: MRS: Pushing multiroomSync output May 22 16:29:54 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:54 volumio-miro volumio[1343]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect May 22 16:29:54 volumio-miro volumio[1343]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"Aries","artist":"Alejandro De Pinedo","album":"Zodiac","albumart":"https://i.scdn.co/image/ab67616d0000b273945ca08ec54b6c471d259faa","uri":"spotify:track:2LIHxCYJteBphA7orZpQpP","trackType":"spotify","codec":"ogg","seek":0,"duration":278,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.105","model":"TX-RZ3400"} May 22 16:29:54 volumio-miro volumio[1343]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"Aries","artist":"Alejandro De Pinedo","album":"Zodiac","albumart":"https://i.scdn.co/image/ab67616d0000b273945ca08ec54b6c471d259faa","uri":"spotify:track:2LIHxCYJteBphA7orZpQpP","trackType":"spotify","codec":"ogg","seek":0,"duration":278,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.105","model":"TX-RZ3400"} May 22 16:29:55 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:55 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:55 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:55 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:55 volumio-miro sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 22 16:29:55 volumio-miro sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:55 volumio-miro volumiobt[2471]: pulseaudio: no process found May 22 16:29:55 volumio-miro sudo[2522]: pam_unix(sudo:session): session closed for user root May 22 16:29:55 volumio-miro dbus-daemon[570]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=2533 comm="/usr/bin/pulseaudio --high-priority=true ") May 22 16:29:55 volumio-miro systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 22 16:29:55 volumio-miro kernel: IPVS: Creating netns size=1496 id=3 May 22 16:29:55 volumio-miro kernel: IPVS: ftp: loaded support on port[0] = 21 May 22 16:29:55 volumio-miro volumio[1343]: ------------------------------------ BT MESSAGE: BT STATUS: running May 22 16:29:55 volumio-miro dbus-daemon[570]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 22 16:29:55 volumio-miro systemd[1]: Started RealtimeKit Scheduling Policy Service. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Successfully called chroot. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Successfully dropped privileges. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Successfully limited resources. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Running. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Canary thread running. May 22 16:29:55 volumio-miro rtkit-daemon[2534]: Watchdog thread running. May 22 16:29:55 volumio-miro dbus-daemon[570]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=2534 comm="/usr/lib/rtkit/rtkit-daemon ") May 22 16:29:55 volumio-miro systemd[1]: Starting Authorization Manager... May 22 16:29:55 volumio-miro volumio[1343]: ------------------------------------ BT MESSAGE: BT STATUS: running May 22 16:29:55 volumio-miro polkitd[2538]: started daemon version 0.105 using authority implementation `local' version `0.105' May 22 16:29:55 volumio-miro dbus-daemon[570]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 22 16:29:55 volumio-miro systemd[1]: Started Authorization Manager. May 22 16:29:55 volumio-miro pulseaudio[2533]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 22 16:29:55 volumio-miro pulseaudio[2533]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 22 16:29:55 volumio-miro volumiobt[2471]: Applying permissions May 22 16:29:55 volumio-miro volumiobt[2471]: Setting BT discoverable and pairable May 22 16:29:55 volumio-miro bluetoothd[606]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource May 22 16:29:55 volumio-miro bluetoothd[606]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink May 22 16:29:55 volumio-miro pulseaudio[2533]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 22 16:29:55 volumio-miro volumiobt[2471]: Agent registered May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# -e power on May 22 16:29:55 volumio-miro volumiobt[2471]: Invalid command in menu main: -e May 22 16:29:55 volumio-miro volumiobt[2471]: May 22 16:29:55 volumio-miro volumiobt[2471]: Use "help" for a list of available commands in a menu. May 22 16:29:55 volumio-miro volumiobt[2471]: Use "menu " if you want to enter any submenu. May 22 16:29:55 volumio-miro volumiobt[2471]: Use "back" if you want to return to menu main. May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# agent on May 22 16:29:55 volumio-miro volumiobt[2471]: Agent is already registered May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# discoverable on May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# pairable on May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# agent NoInputNoOutput May 22 16:29:55 volumio-miro volumiobt[2471]: Agent is already registered May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# default-agent May 22 16:29:55 volumio-miro volumiobt[2471]: [bluetooth]# quit May 22 16:29:55 volumio-miro volumiobt[2471]: [59B blob data] May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:55 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:55 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:55 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.108:3000 from 192.168.68.106 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:55 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:29:55 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:29:56 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:56 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:56 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:56 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:56 volumio-miro volumio[1343]: info: MRS: Getting audio outputs on start May 22 16:29:56 volumio-miro volumio[1343]: info: MRS: Requesting all other devices output May 22 16:29:57 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:57 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:57 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:57 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:57 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:57 volumio-miro volumio[1343]: info: TidalConnect service started! May 22 16:29:57 volumio-miro volumio[1343]: [Metrics] CommandRouter: 33s 534.86ms May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::volumiosetStartupVolume May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:29:57 volumio-miro sudo[2561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::Close All Modals sent May 22 16:29:57 volumio-miro sudo[2565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::Close All Modals sent May 22 16:29:57 volumio-miro sudo[2561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:57 volumio-miro sudo[2561]: pam_unix(sudo:session): session closed for user root May 22 16:29:57 volumio-miro sudo[2565]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:57 volumio-miro sudo[2565]: pam_unix(sudo:session): session closed for user root May 22 16:29:57 volumio-miro volumio[1343]: info: FusionDsp - ---- read samplerate, raw: May 22 16:29:57 volumio-miro volumio[1343]: error: FusionDsp - invalid sample rate May 22 16:29:57 volumio-miro volumio[1343]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 22 16:29:57 volumio-miro volumio[1343]: info: FusionDsp - ---- read samplerate from file: 44100 May 22 16:29:57 volumio-miro volumio[1343]: info: camilladsp stopping service pid 1921... May 22 16:29:57 volumio-miro volumio[1343]: info: camilladsp service terminated, instance 1 May 22 16:29:57 volumio-miro volumio[1343]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 22 16:29:57 volumio-miro volumio[1343]: info: camilladsp service started and running in background, instance 1 May 22 16:29:57 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.116 from 192.168.68.106 UA: Mozilla/5.0 (Linux; Android 15; 24030PN60G Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetVisibleSources May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:57 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:29:57 volumio-miro sudo[2596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:29:57 volumio-miro sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:29:57 volumio-miro sudo[2596]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:57 volumio-miro sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:29:57 volumio-miro sudo[2596]: pam_unix(sudo:session): session closed for user root May 22 16:29:57 volumio-miro sudo[2598]: pam_unix(sudo:session): session closed for user root May 22 16:29:57 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.106 UA: Mozilla/5.0 (Linux; Android 15; 24030PN60G Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:57 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 16:29:57 volumio-miro volumio[1343]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 22 16:29:57 volumio-miro volumio[1343]: info: Listing playlists May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:29:57 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:29:58 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:58 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:58 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:58 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:58 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 22 16:29:58 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 22 16:29:58 volumio-miro volumio[1343]: No protocol specified May 22 16:29:58 volumio-miro volumio[1343]: xcb_connection_has_error() returned true May 22 16:29:58 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 22 16:29:59 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:59 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:59 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:29:59 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:29:59 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:59 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:59 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:29:59 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:59 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:29:59 volumio-miro volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 22 16:29:59 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:29:59 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:00 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:00 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:00 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:00 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:00 volumio-miro volumio[1343]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 16:30:00 volumio-miro volumio[1343]: info: Received Get System Version May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:30:00 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:00 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:00 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 22 16:30:00 volumio-miro volumio[1343]: info: Received Get System Version May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:30:00 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds May 22 16:30:01 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:01 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:01 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:01 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:30:01 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:01 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:01 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 16:30:01 volumio-miro volumio-remote-updater[555]: No test mode May 22 16:30:01 volumio-miro volumio-remote-updater[555]: No alpha test mode May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 22 16:30:01 volumio-miro volumio[1343]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 22 16:30:01 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 22 16:30:02 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:02 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:02 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:02 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:03 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:03 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:03 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:03 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:03 volumio-miro go-librespot[1728]: time="2025-05-22T16:30:03+02:00" level=trace msg="sent dealer ping" May 22 16:30:03 volumio-miro go-librespot[1728]: time="2025-05-22T16:30:03+02:00" level=trace msg="received dealer pong" May 22 16:30:04 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:04 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:04 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:04 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:04 volumio-miro volumio[1343]: info: BOOT COMPLETED May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetBrowseSources May 22 16:30:04 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:30:05 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:05 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:05 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:05 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:06 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:06 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:06 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:06 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:06 volumio-miro volumio[1343]: info: CALLMETHOD: system_controller system saveHDMISettings [object Object] May 22 16:30:06 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , saveHDMISettings May 22 16:30:06 volumio-miro sudo[2664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service May 22 16:30:06 volumio-miro sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:06 volumio-miro systemd[1]: Stopping Start Volumio Kiosk... May 22 16:30:06 volumio-miro systemd[1]: volumio-kiosk.service: Main process exited, code=killed, status=15/TERM May 22 16:30:06 volumio-miro startkiosk.sh[1345]: xinit: connection to X server lost May 22 16:30:06 volumio-miro startkiosk.sh[1345]: [1B blob data] May 22 16:30:06 volumio-miro volumio[1343]: ------------------------------------ BT MESSAGE: BT STATUS: running May 22 16:30:07 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:07 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:07 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:07 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:07 volumio-miro kernel: rockchip_rk3066_pll_set_rate: Invalid rate : 593407000 for pll clk pll_npll May 22 16:30:07 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: PHY configuration failed (clock 593407000) May 22 16:30:07 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:30:07 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:30:07 volumio-miro startkiosk.sh[1345]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. May 22 16:30:07 volumio-miro startkiosk.sh[1345]: xinit: unexpected signal 15 May 22 16:30:07 volumio-miro systemd[1]: volumio-kiosk.service: Succeeded. May 22 16:30:07 volumio-miro systemd[1]: Stopped Start Volumio Kiosk. May 22 16:30:07 volumio-miro systemd[1]: Started Start Volumio Kiosk. May 22 16:30:07 volumio-miro sudo[2664]: pam_unix(sudo:session): session closed for user root May 22 16:30:07 volumio-miro volumio[1343]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Starting Volumio Kiosk with arguments for cursor display May 22 16:30:07 volumio-miro sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable volumio-kiosk.service May 22 16:30:07 volumio-miro sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:07 volumio-miro systemd[1]: Reloading. May 22 16:30:07 volumio-miro startkiosk.sh[2674]: X.Org X Server 1.20.4 May 22 16:30:07 volumio-miro startkiosk.sh[2674]: X Protocol Version 11, Revision 0 May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Current Operating System: Linux volumio-miro 4.4.71+ #23 SMP Sun Sep 20 12:23:26 CEST 2020 armv7l May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Kernel command line: earlyprintk quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear console=tty1 console=ttyS3,115200n8 rw init=/sbin/init imgpart=UUID=a32bdd9f-4394-4094-acb5-0caaf5cc0c55 imgfile=/volumio_current.sqsh bootpart=UUID=0A1E-49F8 datapart=UUID=e2476203-9503-4ba1-9824-4ed6b229d5aa uuidconfig=/extlinux/extlinux.conf vt.global_cursor_default=0 bootdelay=5 uboot_version=2017.07-g879646d1d6 root=/dev/mmcblk1p2 May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Build Date: 18 December 2021 09:05:36AM May 22 16:30:07 volumio-miro startkiosk.sh[2674]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Current version of pixman: 0.36.0 May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Before reporting problems, check http://wiki.x.org May 22 16:30:07 volumio-miro startkiosk.sh[2674]: to make sure that you have the latest version. May 22 16:30:07 volumio-miro startkiosk.sh[2674]: Markers: (--) probed, (**) from config file, (==) default setting, May 22 16:30:07 volumio-miro startkiosk.sh[2674]: (++) from command line, (!!) notice, (II) informational, May 22 16:30:07 volumio-miro startkiosk.sh[2674]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 22 16:30:07 volumio-miro startkiosk.sh[2674]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 22 16:30:07 2025 May 22 16:30:07 volumio-miro startkiosk.sh[2674]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 22 16:30:07 volumio-miro startkiosk.sh[2674]: gbm: failed to open any driver (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) May 22 16:30:07 volumio-miro startkiosk.sh[2674]: gbm: Last dlopen error: /usr/lib/dri/rockchip_dri.so: cannot open shared object file: No such file or directory May 22 16:30:07 volumio-miro startkiosk.sh[2674]: failed to load driver: rockchip May 22 16:30:08 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:08 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:08 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:08 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:08 volumio-miro 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. May 22 16:30:08 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:30:08 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:30:08 volumio-miro sudo[2688]: pam_unix(sudo:session): session closed for user root May 22 16:30:08 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 2 May 22 16:30:08 volumio-miro kernel: dwhdmi-rockchip ff980000.hdmi: event 3 May 22 16:30:08 volumio-miro volumio[1343]: info: enable volumio-kiosk service success May 22 16:30:08 volumio-miro startkiosk.sh[2674]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 22 16:30:08 volumio-miro volumiologrotate[554]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 22 16:30:08 volumio-miro volumiologrotate[554]: ls: cannot access 'MIRO': No such file or directory May 22 16:30:09 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:09 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:09 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:09 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:10 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:10 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:10 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:10 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:10 volumio-miro volumio-remote-updater[555]: No test mode May 22 16:30:10 volumio-miro volumio-remote-updater[555]: No alpha test mode May 22 16:30:10 volumio-miro volumio[1343]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 22 16:30:10 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 22 16:30:11 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:11 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:11 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:11 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:12 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:12 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:12 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:12 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:12 volumio-miro volumio[1343]: info: CoreCommandRouter::Close All Modals sent May 22 16:30:13 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:13 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:13 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:13 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:14 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:14 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:14 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:14 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:15 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:15 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:15 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:15 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:16 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:16 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:16 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:16 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:16 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:16 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:17 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:17 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:17 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:17 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:17 volumio-miro sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:30:17 volumio-miro sudo[2999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:30:17 volumio-miro sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:17 volumio-miro sudo[2999]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:17 volumio-miro sudo[2997]: pam_unix(sudo:session): session closed for user root May 22 16:30:17 volumio-miro sudo[2999]: pam_unix(sudo:session): session closed for user root May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:17 volumio-miro volumio[1343]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 16:30:17 volumio-miro volumio[1343]: info: Received Get System Version May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:30:17 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:17 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:17 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:17 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.106 UA: Mozilla/5.0 (Linux; Android 15; 24030PN60G Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetVisibleSources May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:17 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:17 volumio-miro sudo[3017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:30:17 volumio-miro sudo[3017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:17 volumio-miro sudo[3020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:30:17 volumio-miro sudo[3017]: pam_unix(sudo:session): session closed for user root May 22 16:30:17 volumio-miro sudo[3020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:17 volumio-miro sudo[3020]: pam_unix(sudo:session): session closed for user root May 22 16:30:17 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.106 UA: Mozilla/5.0 (Linux; Android 15; 24030PN60G Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:17 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 16:30:17 volumio-miro volumio[1343]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 22 16:30:17 volumio-miro volumio[1343]: info: Listing playlists May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:30:17 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:18 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:18 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:18 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:18 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:18 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:18 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:18 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:18 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:18 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:18 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:19 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:19 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:19 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:19 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:30:19 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:19 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:19 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:19 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:19 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 13 May 22 16:30:20 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:20 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:20 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:20 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:30:20 volumio-miro volumio[1343]: info: Received Get System Info May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 16:30:20 volumio-miro volumio[1343]: info: Discovery: Getting this device information May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:20 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetVisibleSources May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:20 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:30:20 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:21 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:21 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:21 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:21 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:21 volumio-miro volumio[1343]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 14 May 22 16:30:21 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:21 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:22 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:22 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:22 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:22 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 16:30:22 volumio-miro volumio[1343]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 22 16:30:22 volumio-miro volumio[1343]: info: Listing playlists May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 16:30:22 volumio-miro volumio[1343]: info: Enabling plugin ampswitch May 22 16:30:22 volumio-miro volumio[1343]: info: Loading plugin "ampswitch"... May 22 16:30:22 volumio-miro volumio[1343]: info: Applying required configuration parameters for plugin ampswitch May 22 16:30:22 volumio-miro volumio[1343]: info: PLUGIN START: ampswitch May 22 16:30:22 volumio-miro kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted May 22 16:30:22 volumio-miro volumio[1343]: info: Done. May 22 16:30:22 volumio-miro volumio[1343]: info: CoreCommandRouter::volumioGetState May 22 16:30:22 volumio-miro volumio[1343]: info: CorePlayQueue::getTrack 0 May 22 16:30:22 volumio-miro volumio[1343]: info: [ASDebug] CurState: stop PrevState: na May 22 16:30:22 volumio-miro volumio[1343]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 16:30:22 volumio-miro volumio[1343]: info: [ASDebug] CurState: stop PrevState: na May 22 16:30:22 volumio-miro volumio[1343]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 16:30:23 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:23 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:23 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:23 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:23 volumio-miro volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 16:30:23 volumio-miro volumio[1343]: info: [ASDebug] Togle GPIO: OFF May 22 16:30:23 volumio-miro volumio[1343]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:30:23 volumio-miro volumio[1343]: Error: EPERM: operation not permitted, write May 22 16:30:23 volumio-miro volumio[1343]: at Object.writeSync (fs.js:737:3) May 22 16:30:23 volumio-miro volumio[1343]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 22 16:30:23 volumio-miro volumio[1343]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) May 22 16:30:23 volumio-miro volumio[1343]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) May 22 16:30:23 volumio-miro volumio[1343]: at listOnTimeout (internal/timers.js:557:17) May 22 16:30:23 volumio-miro volumio[1343]: at processTimers (internal/timers.js:500:7) { May 22 16:30:23 volumio-miro volumio[1343]: errno: -1, May 22 16:30:23 volumio-miro volumio[1343]: syscall: 'write', May 22 16:30:23 volumio-miro volumio[1343]: code: 'EPERM' May 22 16:30:23 volumio-miro volumio[1343]: } May 22 16:30:23 volumio-miro volumio[1343]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:30:23 volumio-miro sudo[3066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:29 May 22 16:30:23 volumio-miro sudo[3066]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:30:24 volumio-miro lircd[1993]: lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:24 volumio-miro lircd[1993]: lircd-0.10.1[1993]: default_init(): No such file or directory May 22 16:30:24 volumio-miro lircd-0.10.1[1993]: Error: could not get file information for /dev/lirc0 May 22 16:30:24 volumio-miro lircd-0.10.1[1993]: default_init(): No such file or directory PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"