-- Logs begin at Sat 2025-05-17 16:10:31 CST, end at Sat 2025-05-17 16:12:42 CST. --
May 17 16:11:00 volumio wireless.js[731]: trying...
May 17 16:11:00 volumio sudo[1210]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 17 16:11:00 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:00 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root
May 17 16:11:01 volumio volumio-remote-updater[715]: [2025-05-17 16:11:01] [info] asio async_connect error: system:111 (Connection refused)
May 17 16:11:01 volumio volumio-remote-updater[715]: [2025-05-17 16:11:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 17 16:11:01 volumio volumio-remote-updater[715]: [2025-05-17 16:11:01] [error] handle_connect error: Underlying Transport Error
May 17 16:11:01 volumio wireless.js[731]: trying...
May 17 16:11:01 volumio sudo[1213]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 17 16:11:01 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:01 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root
May 17 16:11:01 volumio avahi-daemon[708]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.173.
May 17 16:11:01 volumio avahi-daemon[708]: New relevant interface wlan0.IPv4 for mDNS.
May 17 16:11:01 volumio avahi-daemon[708]: Registering new address record for 192.168.31.173 on wlan0.IPv4.
May 17 16:11:01 volumio dhcpcd[801]: wlan0: adding route to 192.168.31.0/24
May 17 16:11:01 volumio dhcpcd[801]: wlan0: adding default route via 192.168.31.1
May 17 16:11:01 volumio ntpd[1166]: ntpd exiting on signal 15 (Terminated)
May 17 16:11:01 volumio ntpd[1166]: 119.28.183.184 local addr 192.168.31.164 -> 
May 17 16:11:01 volumio ntpd[1166]: 193.182.111.14 local addr 192.168.31.164 -> 
May 17 16:11:01 volumio systemd[1]: Stopping Network Time Service...
May 17 16:11:01 volumio ntpd[1166]: 202.118.1.81 local addr 192.168.31.164 -> 
May 17 16:11:01 volumio systemd[1]: ntp.service: Succeeded.
May 17 16:11:01 volumio systemd[1]: Stopped Network Time Service.
May 17 16:11:01 volumio systemd[1]: Starting Network Time Service...
May 17 16:11:01 volumio ntpd[1249]: ntpd 4.2.8p12@1.3728-o (1): Starting
May 17 16:11:01 volumio ntpd[1249]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
May 17 16:11:01 volumio systemd[1]: Started Network Time Service.
May 17 16:11:01 volumio ntpd[1255]: proto: precision = 0.315 usec (-22)
May 17 16:11:01 volumio ntpd[1255]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 17 16:11:01 volumio ntpd[1255]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 17 16:11:01 volumio ntpd[1255]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 872 days ago
May 17 16:11:01 volumio ntpd[1255]: Listen and drop on 0 v6wildcard [::]:123
May 17 16:11:01 volumio ntpd[1255]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 17 16:11:01 volumio ntpd[1255]: Listen normally on 2 lo 127.0.0.1:123
May 17 16:11:01 volumio ntpd[1255]: Listen normally on 3 eth0 192.168.31.164:123
May 17 16:11:01 volumio ntpd[1255]: Listen normally on 4 wlan0 192.168.31.173:123
May 17 16:11:01 volumio ntpd[1255]: Listening on routing socket on fd #21 for interface updates
May 17 16:11:01 volumio ntpd[1255]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 17 16:11:01 volumio ntpd[1255]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 17 16:11:02 volumio wireless.js[731]: trying...
May 17 16:11:02 volumio sudo[1258]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 17 16:11:02 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:02 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root
May 17 16:11:02 volumio wireless.js[731]: Connected to: ----Redmi_ZJ_5G
May 17 16:11:02 volumio wireless.js[731]: ----
May 17 16:11:02 volumio sudo[1261]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 17 16:11:02 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:02 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root
May 17 16:11:02 volumio wireless.js[731]: ... joined AP, wlan0 IPv4 is 192.168.31.173, ipV6 is undefined
May 17 16:11:02 volumio wireless.js[731]: It's done! AP
May 17 16:11:02 volumio ntpd[1255]: Soliciting pool server 119.28.183.184
May 17 16:11:03 volumio nmbd[907]: [2025/05/17 16:11:03.540344,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 17 16:11:03 volumio nmbd[907]:   daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
May 17 16:11:03 volumio systemd[1]: Started Samba NMB Daemon.
May 17 16:11:03 volumio systemd[1]: Starting Samba Winbind Daemon...
May 17 16:11:03 volumio systemd[1]: Started Wireless Services.
May 17 16:11:03 volumio systemd[1]: Started Volumio Backend Module.
May 17 16:11:03 volumio systemd[1]: Started Volumio Cpu Tweaker.
May 17 16:11:03 volumio systemd[1]: Started Intel SST and HDA soundcard init service.
May 17 16:11:03 volumio systemd[1]: Started Start Volumio Kiosk.
May 17 16:11:03 volumio ntpd[1255]: Soliciting pool server 193.182.111.14
May 17 16:11:03 volumio ntpd[1255]: Soliciting pool server 183.177.72.202
May 17 16:11:04 volumio ntpd[1255]: Soliciting pool server 193.182.111.141
May 17 16:11:04 volumio ntpd[1255]: Soliciting pool server 183.177.72.201
May 17 16:11:05 volumio nmbd[907]: [2025/05/17 16:11:05.208326,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 17 16:11:05 volumio nmbd[907]:   query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.164 for name WORKGROUP<1d>.
May 17 16:11:05 volumio nmbd[907]:   This response was from IP 192.168.31.210, reporting an IP address of 192.168.31.210.
May 17 16:11:05 volumio nmbd[907]: [2025/05/17 16:11:05.220757,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 17 16:11:05 volumio nmbd[907]:   query_name_response: Multiple (3) responses received for a query on subnet 192.168.31.164 for name WORKGROUP<1d>.
May 17 16:11:05 volumio nmbd[907]:   This response was from IP 192.168.31.210, reporting an IP address of 192.168.31.210.
May 17 16:11:05 volumio nmbd[907]: [2025/05/17 16:11:05.221079,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 17 16:11:05 volumio nmbd[907]:   query_name_response: Multiple (4) responses received for a query on subnet 192.168.31.164 for name WORKGROUP<1d>.
May 17 16:11:05 volumio nmbd[907]:   This response was from IP 192.168.31.210, reporting an IP address of 192.168.31.210.
May 17 16:11:05 volumio volumio-cpu-tweak[1268]: Setting RT Priority for mpd
May 17 16:11:05 volumio volumio-cpu-tweak[1268]: pid 35's current scheduling policy: SCHED_OTHER
May 17 16:11:05 volumio volumio-cpu-tweak[1268]: pid 35's current scheduling priority: 0
May 17 16:11:05 volumio volumio-cpu-tweak[1268]: Not enough cores to set MPD affinity
May 17 16:11:05 volumio volumio-cpu-tweak[1268]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
May 17 16:11:05 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
May 17 16:11:05 volumio ntpd[1255]: Soliciting pool server 202.118.1.81
May 17 16:11:06 volumio systemd[1]: systemd-fsckd.service: Succeeded.
May 17 16:11:06 volumio ntpd[1255]: Soliciting pool server 162.159.200.123
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.255798,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.31.173.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.256138,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.31.173
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.256302,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.31.173.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.256517,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.31.173
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.256666,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.31.173.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.256801,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.31.173
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257163,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.31.164.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257313,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.31.164
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257443,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.31.164.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257579,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.31.164
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257708,  0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed)
May 17 16:11:07 volumio nmbd[907]:   my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.31.164.
May 17 16:11:07 volumio nmbd[907]: [2025/05/17 16:11:07.257841,  0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register)
May 17 16:11:07 volumio nmbd[907]:   standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.31.164
May 17 16:11:09 volumio screensaver.sh[703]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused
May 17 16:11:09 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
May 17 16:11:11 volumio volumio-remote-updater[715]: [2025-05-17 16:11:11] [info] asio async_connect error: system:111 (Connection refused)
May 17 16:11:11 volumio volumio-remote-updater[715]: [2025-05-17 16:11:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 17 16:11:11 volumio volumio-remote-updater[715]: [2025-05-17 16:11:11] [error] handle_connect error: Underlying Transport Error
May 17 16:11:16 volumio winbindd[1264]: [2025/05/17 16:11:16.678729,  0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
May 17 16:11:16 volumio winbindd[1264]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
May 17 16:11:16 volumio winbindd[1264]: [2025/05/17 16:11:16.708640,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 17 16:11:16 volumio systemd[1]: Started Samba Winbind Daemon.
May 17 16:11:16 volumio winbindd[1264]:   daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
May 17 16:11:16 volumio systemd[1]: Starting Samba SMB Daemon...
May 17 16:11:16 volumio startx[1270]: X.Org X Server 1.20.4
May 17 16:11:16 volumio startx[1270]: X Protocol Version 11, Revision 0
May 17 16:11:16 volumio startx[1270]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
May 17 16:11:16 volumio startx[1270]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
May 17 16:11:16 volumio startx[1270]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d0822069-d48a-490f-b8bc-9970c0b6e384 bootpart=UUID=1496-1547 datapart=UUID=a0bf3376-7b72-4c09-9fc2-d0970ff7651c hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd
May 17 16:11:16 volumio startx[1270]: Build Date: 18 December 2021  09:05:36AM
May 17 16:11:16 volumio startx[1270]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
May 17 16:11:16 volumio startx[1270]: Current version of pixman: 0.36.0
May 17 16:11:16 volumio startx[1270]:         Before reporting problems, check http://wiki.x.org
May 17 16:11:16 volumio startx[1270]:         to make sure that you have the latest version.
May 17 16:11:16 volumio startx[1270]: Markers: (--) probed, (**) from config file, (==) default setting,
May 17 16:11:16 volumio startx[1270]:         (++) from command line, (!!) notice, (II) informational,
May 17 16:11:16 volumio startx[1270]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
May 17 16:11:16 volumio startx[1270]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat May 17 16:11:16 2025
May 17 16:11:16 volumio startx[1270]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
May 17 16:11:17 volumio startx[1270]: (II) [KMS] Kernel modesetting enabled.
May 17 16:11:18 volumio smbd[1340]: [2025/05/17 16:11:18.585204,  0] ../lib/util/become_daemon.c:138(daemon_ready)
May 17 16:11:18 volumio smbd[1340]:   daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
May 17 16:11:18 volumio systemd[1]: Started Samba SMB Daemon.
May 17 16:11:18 volumio systemd[1]: Reached target Multi-User System.
May 17 16:11:18 volumio systemd[1]: Reached target Graphical Interface.
May 17 16:11:18 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 17 16:11:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 17 16:11:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
May 17 16:11:19 volumio systemd[1]: soundcard-init.service: Succeeded.
May 17 16:11:20 volumio screensaver.sh[703]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused
May 17 16:11:20 volumio systemd[1]: apt-daily.service: Succeeded.
May 17 16:11:20 volumio systemd[1]: Started Daily apt download activities.
May 17 16:11:20 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
May 17 16:11:21 volumio volumio-remote-updater[715]: [2025-05-17 16:11:21] [connect] Successful connection
May 17 16:11:22 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:22 volumio volumio[1267]: info: -----            Volumio3              ----
May 17 16:11:22 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:22 volumio volumio[1267]: info: -----          System startup          ----
May 17 16:11:22 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:23 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
May 17 16:11:23 volumio systemd[1]: Started Daily apt upgrade and clean activities.
May 17 16:11:23 volumio systemd[1]: Startup finished in 34.300s (kernel) + 54.248s (userspace) = 1min 28.549s.
May 17 16:11:25 volumio volumio[1267]: info: MYVOLUMIO Environment detected
May 17 16:11:25 volumio volumio[1267]: info: Plugin folders cleanup
May 17 16:11:25 volumio volumio[1267]: info: Scanning into folder /volumio/app/plugins/
May 17 16:11:25 volumio volumio[1267]: info: Scanning category audio_interface
May 17 16:11:25 volumio volumio[1267]: info: Scanning category miscellanea
May 17 16:11:25 volumio volumio[1267]: info: Scanning category music_service
May 17 16:11:25 volumio volumio[1267]: info: Scanning category plugins.json
May 17 16:11:25 volumio volumio[1267]: info: Scanning category system_controller
May 17 16:11:25 volumio volumio[1267]: info: Scanning category user_interface
May 17 16:11:25 volumio volumio[1267]: info: Scanning into folder /data/plugins/
May 17 16:11:25 volumio volumio[1267]: info: Scanning category music_service
May 17 16:11:25 volumio volumio[1267]: info: Scanning category system_controller
May 17 16:11:25 volumio volumio[1267]: info: Scanning category user_interface
May 17 16:11:25 volumio volumio[1267]: info: Plugin folders cleanup completed
May 17 16:11:25 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:25 volumio volumio[1267]: info: -----      Core plugins startup        ----
May 17 16:11:25 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:25 volumio volumio[1267]: info: Loading plugins from folder /volumio/app/plugins/
May 17 16:11:25 volumio volumio[1267]: info: Adding plugin upnp to MyMusic Plugins
May 17 16:11:25 volumio volumio[1267]: info: Adding plugin airplay_emulation to MyMusic Plugins
May 17 16:11:25 volumio volumio[1267]: info: Adding plugin upnp_browser to MyMusic Plugins
May 17 16:11:25 volumio volumio[1267]: info: Loading plugins from folder /data/plugins/
May 17 16:11:25 volumio volumio[1267]: info: Loading plugin "system"...
May 17 16:11:26 volumio volumio[1267]: info: Loading plugin "appearance"...
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "network"...
May 17 16:11:30 volumio volumio[1267]: info: Refreshing Cached IP Addresses
May 17 16:11:30 volumio sudo[1593]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 17 16:11:30 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:30 volumio sudo[1595]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 17 16:11:30 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "services"...
May 17 16:11:30 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root
May 17 16:11:30 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "alsa_controller"...
May 17 16:11:30 volumio sudo[1598]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 17 16:11:30 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:30 volumio volumio[1267]: warn: Unable to locate the audio output device HDMI. Please configure a valid output device.
May 17 16:11:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "wizard"...
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "networkfs"...
May 17 16:11:30 volumio volumio[1267]: info: Starting Udev Watcher for removable devices
May 17 16:11:30 volumio volumio[1267]: info: Ignoring mount for partition: boot
May 17 16:11:30 volumio volumio[1267]: info: Ignoring mount for partition: volumio
May 17 16:11:30 volumio volumio[1267]: info: Ignoring mount for partition: volumio_data
May 17 16:11:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "volumio_command_line_client"...
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "upnp"...
May 17 16:11:30 volumio volumio[1267]: info: [1747469490866] Starting Upmpd Daemon
May 17 16:11:30 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "my_music"...
May 17 16:11:30 volumio volumio[1267]: info: Loading plugin "mpd"...
May 17 16:11:31 volumio volumio[1267]: info: Loading plugin "upnp_browser"...
May 17 16:11:32 volumio volumio[1267]: info: Loading plugin "alarm-clock"...
May 17 16:11:32 volumio volumio[1267]: info: Loading plugin "airplay_emulation"...
May 17 16:11:32 volumio volumio[1267]: info: Starting Shairport Sync
May 17 16:11:32 volumio volumio[1267]: info: Loading plugin "last_100"...
May 17 16:11:32 volumio volumio[1267]: info: Loading plugin "webradio"...
May 17 16:11:33 volumio volumio[1267]: info: Loading plugin "i2s_dacs"...
May 17 16:11:33 volumio volumio[1267]: info: I2S DAC not set, start Auto-detection
May 17 16:11:33 volumio volumio[1267]: info: Loading plugin "volumiodiscovery"...
May 17 16:11:33 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** For more information see 
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 17 16:11:33 volumio volumio[1267]: *** WARNING *** For more information see 
May 17 16:11:33 volumio node[1267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 17 16:11:33 volumio node[1267]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 17 16:11:33 volumio node[1267]: *** WARNING *** For more information see 
May 17 16:11:33 volumio node[1267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 17 16:11:33 volumio node[1267]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 17 16:11:33 volumio node[1267]: *** WARNING *** For more information see 
May 17 16:11:33 volumio volumio[1267]: info: Applying required configuration parameters for plugin volumiodiscovery
May 17 16:11:33 volumio volumio[1267]: info: Discovery: Started advertising with name: Volumio
May 17 16:11:33 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 17 16:11:33 volumio volumio[1267]: info: Loading plugin "jellyfin"...
May 17 16:11:35 volumio volumio[1267]: info: Loading plugin "jellyfin_server"...
May 17 16:11:36 volumio volumio[1267]: info: Loading plugin "autostart"...
May 17 16:11:36 volumio volumio-remote-updater[715]: [2025-05-17 16:11:36] [connect] Successful connection
May 17 16:11:36 volumio volumio[1267]: info: Applying required configuration parameters for plugin autostart
May 17 16:11:36 volumio volumio[1267]: info: AutoStart - onVolumioStart -  read config.json
May 17 16:11:36 volumio volumio[1267]: info: Loading plugin "peppy_screensaver"...
May 17 16:11:39 volumio volumio[1267]: info: Applying required configuration parameters for plugin peppy_screensaver
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "outputs"...
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "albumart"...
May 17 16:11:39 volumio volumio[1267]: info: Plugin example_plugin is not enabled
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "inputs"...
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "updater_comm"...
May 17 16:11:39 volumio volumio[1267]: Forking 1 albumart workers
May 17 16:11:39 volumio volumio[1267]: info: Plugin mpdemulation is not enabled
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "rest_api"...
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "websocket"...
May 17 16:11:39 volumio volumio[1267]: info: Starting Socket.io Server version 2.3.0
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "RoonBridge"...
May 17 16:11:39 volumio volumio[1267]: info: Applying required configuration parameters for plugin RoonBridge
May 17 16:11:39 volumio volumio[1267]: info: Loading plugin "lms"...
May 17 16:11:40 volumio volumio[1267]: info: Loading plugin "minidlna"...
May 17 16:11:40 volumio volumio[1267]: Starting albumart workers
May 17 16:11:40 volumio volumio[1267]: info: Applying required configuration parameters for plugin minidlna
May 17 16:11:40 volumio volumio[1267]: info: Loading i18n strings for locale zh
May 17 16:11:40 volumio volumio[1267]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 17 16:11:40 volumio volumio[1267]: Updating browse sources language
May 17 16:11:40 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::initPlayerControls
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 17 16:11:41 volumio volumio[1267]: Express server listening on port 3000
May 17 16:11:41 volumio volumio[1267]:  [Metrics]  WebUI:  20s 212.28ms 
May 17 16:11:41 volumio volumio[1267]: info: Setting Device type: x86
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::resetVolumioState
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::getcurrentVolume
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioRetrievevolume
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:41 volumio sudo[1751]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 17 16:11:41 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:41 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root
May 17 16:11:41 volumio volumio[1267]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
May 17 16:11:41 volumio volumio[1267]: info: Completed loading Core Plugins
May 17 16:11:41 volumio volumio[1267]: info: Preparing to generate the ALSA configuration file
May 17 16:11:41 volumio sudo[1753]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 17 16:11:41 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:41 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root
May 17 16:11:41 volumio volumio[1267]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0
May 17 16:11:41 volumio volumio[1267]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
May 17 16:11:41 volumio volumio[1267]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
May 17 16:11:41 volumio volumio[1267]: info: Reading ALSA contributions from plugins.
May 17 16:11:41 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164:3000 from 192.168.31.185 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
May 17 16:11:41 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164:3000 from 192.168.31.185 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:11:41 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:11:41 volumio volumio[1267]: 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 17 16:11:41 volumio volumio[1267]: info: VolumeController:: Volume=undefined Mute =false
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::updateTrackBlock
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrackBlock
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioRetrievevolume
May 17 16:11:41 volumio volumio[1267]: info: Reloading queue from file
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::setRepeat null single undefined
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::setRandom null
May 17 16:11:41 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:11:41 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:11:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
May 17 16:11:42 volumio volumio[1267]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0
May 17 16:11:42 volumio volumio[1267]: info: Discovery: adding a4067e29-9baa-4d5b-8124-fb4505bc930b
May 17 16:11:42 volumio volumio[1267]: info: Discovery: Found device Volumio
May 17 16:11:42 volumio volumio[1267]: info: Discovery: Connecting to remote: 192.168.31.185
May 17 16:11:42 volumio volumio[1267]: info: Discovery: this is already registered,  a4067e29-9baa-4d5b-8124-fb4505bc930b
May 17 16:11:42 volumio volumio[1267]: info: Discovery: Found device Volumio
May 17 16:11:42 volumio volumio[1267]: info: Discovery: Connecting to remote: 192.168.31.185
May 17 16:11:42 volumio sudo[1770]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 17 16:11:42 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:42 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.173:3000 from 192.168.31.108 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4
May 17 16:11:42 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
May 17 16:11:42 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:42 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:42 volumio volumio[1267]: info: VolumeController:: Volume=undefined Mute =false
May 17 16:11:42 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:11:42 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:42 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 17 16:11:42 volumio volumio[1267]: info: Upmpdcli Daemon Started
May 17 16:11:42 volumio volumio[1267]: info: Discovery: Connected to remote: 192.168.31.185
May 17 16:11:42 volumio volumio[1267]: info: Asound.conf file unchanged, so no further update is needed
May 17 16:11:42 volumio volumio[1267]: info: Output device has changed, restarting MPD
May 17 16:11:42 volumio volumio[1267]: info: Output device has changed, restarting Shairport Sync
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:42 volumio sudo[1776]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:42 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:42 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root
May 17 16:11:42 volumio sudo[1779]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 17 16:11:42 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:42 volumio volumio[1267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 17 16:11:42 volumio volumio[1267]: info: ___________ START PLUGINS ___________
May 17 16:11:42 volumio systemd[1]: Listening on mpd.socket.
May 17 16:11:42 volumio volumio[1267]: info: ControllerMpd::onStart: Initializing MPD
May 17 16:11:42 volumio volumio[1267]: info: Creating MPD Configuration file
May 17 16:11:42 volumio systemd[1]: Starting Music Player Daemon...
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 17 16:11:42 volumio volumio[1267]: info: [1747469502831] CoreMusicLibrary::Adding element 媒体服务器
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:11:42 volumio sudo[1788]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 17 16:11:42 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:42 volumio sudo[1786]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:42 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:42 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:42 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root
May 17 16:11:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
May 17 16:11:42 volumio systemd[1]: mpd.service: Succeeded.
May 17 16:11:42 volumio systemd[1]: Stopped Music Player Daemon.
May 17 16:11:42 volumio systemd[1]: Starting Music Player Daemon...
May 17 16:11:43 volumio volumio[1267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:43 volumio sudo[1792]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 17 16:11:43 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:43 volumio sudo[1792]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 17 16:11:43 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root
May 17 16:11:43 volumio volumio[1267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 17 16:11:43 volumio volumio[1267]: info: [1747469503199] CoreMusicLibrary::Adding element Last_100
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 17 16:11:43 volumio volumio[1267]: info: [1747469503206] CoreMusicLibrary::Adding element Webradio
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 17 16:11:43 volumio volumio[1267]: info: Initializing BBC Radios
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:11:43 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:43 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 17 16:11:43 volumio volumio[1267]: info: [1747469503492] CoreMusicLibrary::Adding element Jellyfin
May 17 16:11:43 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:11:43 volumio volumio[1267]: Cannot find translation for source Jellyfin
May 17 16:11:43 volumio volumio[1267]: info: [jellyfin] Initialized plugin with device info:  {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.31.164","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.31.164/albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2FUSB&metadata=false"}}
May 17 16:11:43 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true
May 17 16:11:43 volumio volumio[1267]: info: AutoStart - onStart
May 17 16:11:43 volumio volumio[1267]: info: Loading i18n strings for locale zh
May 17 16:11:43 volumio volumio[1267]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 17 16:11:43 volumio volumio[1267]: info: /tmp/myfifo created
May 17 16:11:43 volumio sudo[1811]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin
May 17 16:11:43 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:43 volumio volumio[1267]: info: /tmp/myfifosa created
May 17 16:11:43 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway.
May 17 16:11:43 volumio systemd[1]: Starting Jellyfin...
May 17 16:11:43 volumio sudo[1818]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
May 17 16:11:43 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:43 volumio systemd[1]: Started Jellyfin.
May 17 16:11:43 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root
May 17 16:11:43 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root
May 17 16:11:43 volumio volumio[1267]: info: snd-dummy loaded
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:44 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:44 volumio volumio[1267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 17 16:11:44 volumio sudo[1844]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
May 17 16:11:44 volumio volumio[1267]: info: Loading i18n strings for locale zh
May 17 16:11:44 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:44 volumio volumio[1267]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 17 16:11:44 volumio sudo[1846]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver
May 17 16:11:44 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:44 volumio systemd[1]: Started RoonBridge.
May 17 16:11:44 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly.
May 17 16:11:44 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root
May 17 16:11:44 volumio systemd[1]: Starting Logitech Media Server Daemon...
May 17 16:11:44 volumio volumio[1267]: info: Volumio Calling Home
May 17 16:11:44 volumio systemd[1]: Started Logitech Media Server Daemon.
May 17 16:11:45 volumio systemd[1863]: logitechmediaserver.service: Failed to execute command: No such file or directory
May 17 16:11:45 volumio systemd[1863]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory
May 17 16:11:45 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC
May 17 16:11:45 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'.
May 17 16:11:45 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root
May 17 16:11:46 volumio volumio[1267]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785
May 17 16:11:46 volumio volumio[1267]: info: Discovery: Found device Volumio
May 17 16:11:46 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:46 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:46 volumio volumio[1267]: info: Discovery: this is already registered,  6576be8c-9ca3-4f6e-ad09-3e064e726785
May 17 16:11:46 volumio volumio[1267]: info: Discovery: Found device Volumio
May 17 16:11:46 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:46 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:46 volumio volumio[1267]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 17 16:11:46 volumio volumio[1267]: info: logitechmediaserver started
May 17 16:11:46 volumio volumio[1267]: info: MPD Permissions set
May 17 16:11:46 volumio volumio[1267]: info: MPD Permissions set
May 17 16:11:46 volumio volumio[1267]: info: minidlna: Found miniDLNA version 1.2.1
May 17 16:11:46 volumio volumio[1267]: info: minidlna: Starting minidlna.service
May 17 16:11:47 volumio sudo[1869]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
May 17 16:11:47 volumio sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:47 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server.
May 17 16:11:47 volumio sudo[1869]: pam_unix(sudo:session): session closed for user root
May 17 16:11:47 volumio sudo[1875]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl
May 17 16:11:47 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:47 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:48 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:48 volumio RoonBridge[1850]: 00:00:00.042 Warn:  get lock file path: /tmp/.rnbgem0-
May 17 16:11:48 volumio volumio[1267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
May 17 16:11:48 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio sudo[1883]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 17 16:11:48 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:48 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway.
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 17 16:11:48 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:48 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:49 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:49 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 17 16:11:49 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:49 volumio mpd[1799]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 17 16:11:49 volumio mpd[1799]: output: No 'audio_output' defined in config file
May 17 16:11:49 volumio mpd[1799]: couldn't open play stream: No such file or directory
May 17 16:11:49 volumio mpd[1799]: sndio_output: Error opening default sndio device
May 17 16:11:49 volumio mpd[1799]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card
May 17 16:11:49 volumio mpd[1799]: alsa_output: Error opening default ALSA device: No such device
May 17 16:11:49 volumio mpd[1799]: output: Successfully detected a jack audio device
May 17 16:11:49 volumio mpd[1799]: zeroconf: No global port, disabling zeroconf
May 17 16:11:49 volumio systemd[1]: Started Music Player Daemon.
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:01.795 Trace: [childprocess] using unix child process
May 17 16:11:49 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root
May 17 16:11:49 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root
May 17 16:11:49 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile
May 17 16:11:49 volumio volumio[1267]: info: minidlna: systemctl start minidlna.service succeeded.
May 17 16:11:49 volumio volumio[1267]: info: Volumio called home
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.093 Debug: PathForResource, filename: ../.update
May 17 16:11:49 volumio sudo[1904]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 17 16:11:49 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:49 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.208 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:11:49 volumio volumio[1267]: Upnp client error: Error: This socket has been ended by the other party
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.239 Debug: PathForResource, filename: RAATServer
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.239 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.240 Debug: PathForResource, filename: RoonBridgeHelper
May 17 16:11:49 volumio RoonBridge[1850]: 00:00:02.240 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 17 16:11:50 volumio RoonBridge[1850]: Initializing
May 17 16:11:50 volumio RoonBridge[1850]: 00:00:02.491 Info:  Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 17 16:11:50 volumio RoonBridge[1850]: 00:00:02.694 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:11:50 volumio volumio[1267]: info: MPD Permissions set
May 17 16:11:50 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
May 17 16:11:50 volumio volumio[1267]: info: Starting Shairport Sync
May 17 16:11:50 volumio volumio[1267]: info: Starting Shairport Sync
May 17 16:11:50 volumio sudo[1918]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 17 16:11:50 volumio sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:50 volumio systemd[1]: Stopping Music Player Daemon...
May 17 16:11:50 volumio sudo[1921]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 17 16:11:50 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:50 volumio volumio[1267]: info: Starting Shairport Sync
May 17 16:11:50 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:11:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 17 16:11:51 volumio systemd[1]: shairport-sync.service: Succeeded.
May 17 16:11:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio systemd[1]: mpd.service: Succeeded.
May 17 16:11:51 volumio systemd[1]: Stopped Music Player Daemon.
May 17 16:11:51 volumio systemd[1]: Starting Music Player Daemon...
May 17 16:11:51 volumio sudo[1928]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 17 16:11:51 volumio volumio[1267]: info: Starting Shairport Sync
May 17 16:11:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:51 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root
May 17 16:11:51 volumio volumio-remote-updater[715]: [2025-05-17 16:11:51] [connect] Successful connection
May 17 16:11:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 17 16:11:51 volumio systemd[1]: shairport-sync.service: Succeeded.
May 17 16:11:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio sudo[1933]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 17 16:11:51 volumio sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio sudo[1928]: pam_unix(sudo:session): session closed for user root
May 17 16:11:51 volumio sudo[1931]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 17 16:11:51 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:51 volumio sudo[1931]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 17 16:11:51 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root
May 17 16:11:51 volumio volumio[1267]: error: MPD error: Error: write EPIPE
May 17 16:11:51 volumio volumio[1267]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
May 17 16:11:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 17 16:11:51 volumio sudo[1938]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 17 16:11:51 volumio sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:51 volumio systemd[1]: shairport-sync.service: Succeeded.
May 17 16:11:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:51 volumio sudo[1933]: pam_unix(sudo:session): session closed for user root
May 17 16:11:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 17 16:11:51 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
May 17 16:11:51 volumio systemd[1]: shairport-sync.service: Succeeded.
May 17 16:11:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 17 16:11:52 volumio sudo[1938]: pam_unix(sudo:session): session closed for user root
May 17 16:11:52 volumio volumio[1267]: error: MPD error: The expression evaluated to a falsy value:
May 17 16:11:52 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:52 volumio volumio[1267]: error: The expression evaluated to a falsy value:
May 17 16:11:52 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:52 volumio volumio[1267]: error: MPD error: Error: write EPIPE
May 17 16:11:52 volumio volumio[1267]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
May 17 16:11:52 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:52 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:52 volumio volumio[1267]: info: MPD running with PID1799
May 17 16:11:52 volumio volumio[1267]:  ,establishing connection
May 17 16:11:52 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 17 16:11:52 volumio jellyfin[1819]: [16:11:51] [INF] [1] Main: Jellyfin version: 10.9.6
May 17 16:11:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:52 volumio sudo[1952]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 17 16:11:52 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"]
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"]
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster)
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Architecture: X64
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: 64-Bit Process: True
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: User Interactive: True
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Processor count: 2
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Program data path: /opt/jellyfin/data
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Log directory path: /opt/jellyfin/log
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Config directory path: /opt/jellyfin/config
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Cache path: /opt/jellyfin/cache
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web
May 17 16:11:52 volumio jellyfin[1819]: [16:11:52] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/
May 17 16:11:53 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:11:53 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 17 16:11:53 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root
May 17 16:11:53 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:11:53 volumio volumio[1267]: info: Shairport-Sync Started
May 17 16:11:53 volumio volumio[1267]: Error adding Membership: Error: addMembership EINVAL
May 17 16:11:53 volumio volumio[1267]: info: Shairport-Sync Started
May 17 16:11:53 volumio volumio[1267]: info: Shairport-Sync Started
May 17 16:11:53 volumio volumio[1267]: info: Shairport-Sync Started
May 17 16:11:53 volumio RoonBridge[1850]: Running
May 17 16:11:53 volumio volumio-remote-updater[715]: [2025-05-17 16:11:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747469511 101
May 17 16:11:53 volumio volumio[1267]: 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: 5
May 17 16:11:53 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:11:53 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:11:54 volumio screensaver.sh[703]: 1829
May 17 16:11:54 volumio volumio[1267]: warn: peppy_screensaver: An error occurred on pygame check Command failed: python3 -c "import pygame"
May 17 16:11:54 volumio volumio[1267]: Terminated
May 17 16:11:54 volumio volumio[1267]:  {"killed":false,"code":143,"signal":null,"cmd":"python3 -c \"import pygame\"","stack":"Error: Command failed: python3 -c \"import pygame\"\nTerminated\n\n    at ChildProcess.exithandler (child_process.js:383:12)\n    at ChildProcess.emit (events.js:400:28)\n    at maybeClose (internal/child_process.js:1088:16)\n    at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5)"}
May 17 16:11:54 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 17 16:11:54 volumio sudo[1977]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 17 16:11:54 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:54 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root
May 17 16:11:55 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 17 16:11:55 volumio sudo[1989]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 17 16:11:55 volumio sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:55 volumio sudo[1989]: pam_unix(sudo:session): session closed for user root
May 17 16:11:55 volumio volumio[1267]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 17 16:11:56 volumio sudo[2000]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 17 16:11:56 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:11:56 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root
May 17 16:11:56 volumio volumio[1267]: info: Completed starting Core Plugins
May 17 16:11:56 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:56 volumio volumio[1267]: info: -----    MyVolumio plugins startup     ----
May 17 16:11:56 volumio volumio[1267]: info: -------------------------------------------
May 17 16:11:56 volumio volumio[1267]: info: [MyVolumio PluginManager] Fetching plans data....
May 17 16:11:56 volumio volumio[1267]: error: MPD error: The expression evaluated to a falsy value:
May 17 16:11:56 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:56 volumio volumio[1267]: error: The expression evaluated to a falsy value:
May 17 16:11:56 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:56 volumio volumio[1267]: error: MPD error: The expression evaluated to a falsy value:
May 17 16:11:56 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:56 volumio volumio[1267]: error: The expression evaluated to a falsy value:
May 17 16:11:56 volumio volumio[1267]:   assert.ok(self.idling)
May 17 16:11:56 volumio jellyfin[1819]: [16:11:56] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"]
May 17 16:11:57 volumio RoonBridge[1850]: 00:00:00.344 Warn:  get lock file path: /tmp/.rnbhgem0-
May 17 16:11:57 volumio mpd[1942]: May 17 16:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 17 16:11:57 volumio jellyfin[1819]: [16:11:57] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache
May 17 16:11:57 volumio systemd[1]: Started Music Player Daemon.
May 17 16:11:57 volumio sudo[1918]: pam_unix(sudo:session): session closed for user root
May 17 16:11:57 volumio volumio[1267]: Upnp client error: Error: This socket has been ended by the other party
May 17 16:11:57 volumio volumio[1267]: error: updateQueue error: null
May 17 16:11:57 volumio volumio[1267]: error: updateQueue error: null
May 17 16:11:58 volumio jellyfin[1819]: [16:11:58] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"]
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: []
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"]
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.31.164", "192.168.31.173"]
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"]
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist
May 17 16:11:59 volumio jellyfin[1819]: [16:11:59] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: []
May 17 16:12:00 volumio RoonBridge[1850]: 00:00:13.103 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:00 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:00 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:00 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:01 volumio RoonBridge[1850]: 00:00:13.293 Debug: PathForResource, filename: ../.update
May 17 16:12:01 volumio RoonBridge[1850]: 00:00:13.314 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:02 volumio volumio[1773]: Generating RSA private key, 4096 bit long modulus (2 primes)
May 17 16:12:02 volumio volumio[1267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
May 17 16:12:03 volumio RoonBridge[1850]: 00:00:15.314 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:12:03 volumio RoonBridge[1850]: Running
May 17 16:12:03 volumio volumio[1267]: info: AutoStart - Plugin is starting
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::volumioGetQueue
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::getQueue
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getQueue
May 17 16:12:03 volumio volumio[1267]: info: AutoStart - start playing
May 17 16:12:03 volumio volumio[1267]: info: AutoStart - start playing with no specific position
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::volumioPlay
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::play index 0
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::stop
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::play index undefined
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::startPlaybackTimer
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand stop
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand stop took 6 milliseconds
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand clear
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces system playlist update
May 17 16:12:03 volumio volumio[1267]: info: Ignoring MPD Status Update
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand clear took 11 milliseconds
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac"
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces system playlist update
May 17 16:12:03 volumio volumio[1267]: info: Ignoring MPD Status Update
May 17 16:12:03 volumio volumio[1267]: error: updateQueue error: null
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces system playlist update
May 17 16:12:03 volumio volumio[1267]: info: Ignoring MPD Status Update
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 31ms
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 21 milliseconds
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand play
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 28ms
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces system playlist update
May 17 16:12:03 volumio volumio[1267]: info: Ignoring MPD Status Update
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 33ms
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand play took 35 milliseconds
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 37ms
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand status took 99 milliseconds
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand status took 91 milliseconds
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:03 volumio volumio[1267]: info:
May 17 16:12:03 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand status took 33 milliseconds
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand playlistinfo took 24 milliseconds
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand playlistinfo took 20 milliseconds
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand status took 19 milliseconds
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:03 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"305 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:03 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService play
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus stop
May 17 16:12:03 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"379 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:03 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService play
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus play
May 17 16:12:03 volumio volumio[1267]: info: Received an update from plugin. extracting info from payload
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:03 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:03 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:03 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 242ms
May 17 16:12:03 volumio volumio[1267]: info: ------------------------------ 255ms
May 17 16:12:03 volumio volumio[1267]: info: sendMpdCommand playlistinfo took 126 milliseconds
May 17 16:12:04 volumio volumio[1267]: info: sendMpdCommand playlistinfo took 117 milliseconds
May 17 16:12:04 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo
May 17 16:12:04 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:04 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService play
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus play
May 17 16:12:04 volumio volumio[1267]: info: Received an update from plugin. extracting info from payload
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:04 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:04 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService play
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus play
May 17 16:12:04 volumio volumio[1267]: info: Received an update from plugin. extracting info from payload
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:04 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:04 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:04 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:04 volumio volumio[1267]: info: ------------------------------ 344ms
May 17 16:12:04 volumio volumio[1267]: info: ------------------------------ 322ms
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin bluetooth to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin multiroom to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin metavolumio to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin cd_controller to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin smart_inputs to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: Adding plugin tidalconnect to MyMusic Plugins
May 17 16:12:05 volumio volumio[1267]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 17 16:12:07 volumio ntpd[1255]: Soliciting pool server 118.163.81.63
May 17 16:12:09 volumio RoonBridge[1850]: 00:00:21.380 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:09 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:09 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:09 volumio RoonBridge[1850]: 00:00:21.382 Debug: PathForResource, filename: ../.update
May 17 16:12:09 volumio RoonBridge[1850]: 00:00:21.385 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:09 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:09 volumio volumio[1267]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 17 16:12:09 volumio volumio[1267]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 17 16:12:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:09 volumio volumio[1267]: info: Starting MyVolumio Remote Streaming Endpoints
May 17 16:12:09 volumio volumio[1267]: info: MyVolumio login type: Token
May 17 16:12:09 volumio volumio[1267]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 17 16:12:09 volumio volumio[1267]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 17 16:12:10 volumio ntpd[1255]: Soliciting pool server 114.34.171.136
May 17 16:12:11 volumio RoonBridge[1850]: 00:00:23.394 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:12:11 volumio RoonBridge[1850]: Running
May 17 16:12:11 volumio volumio[1267]: info: Starting Streaming Service Transparent Proxy
May 17 16:12:11 volumio volumio[1267]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 17 16:12:11 volumio volumio[1267]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 17 16:12:11 volumio volumio[1267]: info: Streaming services startup
May 17 16:12:11 volumio volumio[1267]: info: Starting Streaming Daemon
May 17 16:12:12 volumio sudo[2237]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 17 16:12:12 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:12 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root
May 17 16:12:12 volumio volumio[1267]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 17 16:12:13 volumio volumio[1267]: error: Cannot start Volumio Streaming Daemon
May 17 16:12:13 volumio volumio[1267]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 17 16:12:13 volumio volumio[1267]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 17 16:12:13 volumio volumio[1267]: Upnp client error: Error: This socket has been ended by the other party
May 17 16:12:13 volumio ntpd[1255]: Soliciting pool server 193.182.111.141
May 17 16:12:13 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164:3000 from 192.168.31.185 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
May 17 16:12:13 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:14 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:14 volumio sudo[2247]:     root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py
May 17 16:12:14 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:14 volumio volumio[1267]: Upnp client error: Error: This socket has been ended by the other party
May 17 16:12:14 volumio volumio[1267]: Upnp client error: Error: This socket has been ended by the other party
May 17 16:12:14 volumio ntpd[1255]: Soliciting pool server 193.182.111.12
May 17 16:12:14 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 17 16:12:14 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:15 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 17 16:12:15 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
May 17 16:12:15 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:15 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:15 volumio volumio[1267]: error: MyVolumio Custom Token format not valid, refreshing it
May 17 16:12:16 volumio volumio-remote-updater[715]: No test mode
May 17 16:12:16 volumio volumio-remote-updater[715]: No alpha test mode
May 17 16:12:16 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 17 16:12:17 volumio RoonBridge[1850]: 00:00:29.454 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:17 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:17 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:17 volumio RoonBridge[1850]: 00:00:29.486 Debug: PathForResource, filename: ../.update
May 17 16:12:17 volumio RoonBridge[1850]: 00:00:29.486 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:17 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:17 volumio volumio[1267]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nVarious Fixes for QOBUZ Connect
\n
\nNEW ADDITIONS
\n\nQobuz Connect Ready
\n\n","title":"Update v3.812","updateavailable":true}
May 17 16:12:18 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 17 16:12:18 volumio volumio[1267]: info: MyVolumio login type: Token
May 17 16:12:18 volumio volumio[1267]: STREAMING PROXY: Starting server on port 3245
May 17 16:12:18 volumio volumio[1267]: Node JS runtime: 14
May 17 16:12:19 volumio RoonBridge[1850]: 00:00:31.499 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:12:19 volumio RoonBridge[1850]: Running
May 17 16:12:20 volumio volumio[1267]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:22 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:22 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:22 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:22 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:22 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164:3000 from 192.168.31.108 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:22 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 17 16:12:22 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 17 16:12:22 volumio volumio[1267]: info: MyVolumio token set successfully
May 17 16:12:22 volumio volumio[1267]: info: MYVOLUMIO: Adding device
May 17 16:12:22 volumio volumio[1267]: info: MYVOLUMIO: Evaluating Server
May 17 16:12:24 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 17 16:12:24 volumio volumio[1267]: info: MyVolumio status changed
May 17 16:12:24 volumio volumio[1267]: info: Streaming services startup
May 17 16:12:24 volumio volumio[1267]: info: Starting Streaming Daemon
May 17 16:12:25 volumio volumio[1267]: info: Removing browser output: myVolumio user plan is not superstar
May 17 16:12:25 volumio volumio[1267]: info: Removing audio output:
May 17 16:12:25 volumio volumio[1267]: info: Stoppping Tunnel 1
May 17 16:12:25 volumio sudo[2299]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 17 16:12:25 volumio sudo[2299]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:25 volumio RoonBridge[1850]: 00:00:37.552 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:25 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:25 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:25 volumio sudo[2299]: pam_unix(sudo:session): session closed for user root
May 17 16:12:25 volumio RoonBridge[1850]: 00:00:37.576 Debug: PathForResource, filename: ../.update
May 17 16:12:25 volumio RoonBridge[1850]: 00:00:37.580 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:25 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:25 volumio sudo[2306]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 17 16:12:25 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:25 volumio volumio[1267]: error: Cannot start Volumio Streaming Daemon
May 17 16:12:25 volumio volumio[1267]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 17 16:12:25 volumio volumio[1267]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 17 16:12:25 volumio volumio[1267]: 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 17 16:12:25 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root
May 17 16:12:25 volumio volumio[1267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
May 17 16:12:25 volumio volumio[1267]: info: Remote SSH Stopped
May 17 16:12:25 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:25 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:25 volumio volumio[1267]: info: Setting Geolocation for MyVolumio to us2
May 17 16:12:25 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:26 volumio volumio[1267]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 17 16:12:26 volumio volumio[1267]: info: Updating MyVolumio device info
May 17 16:12:26 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:26 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:27 volumio volumio[1267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9
May 17 16:12:27 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:27 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:27 volumio RoonBridge[1850]: 00:00:39.582 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:12:27 volumio RoonBridge[1850]: Running
May 17 16:12:27 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:27 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::volumioGetBrowseSources
May 17 16:12:29 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 17 16:12:29 volumio volumio[1267]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 17 16:12:30 volumio volumio[1267]: info: MYVOLUMIO: Adding device
May 17 16:12:30 volumio volumio[1267]: info: MYVOLUMIO: Evaluating Server
May 17 16:12:31 volumio volumio[1773]: ...............................................................................................................................................................................................................................................................................................++++
May 17 16:12:32 volumio volumio[1267]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 17 16:12:32 volumio volumio[1267]: info: Completed starting MyVolumio Plugin
May 17 16:12:32 volumio volumio[1267]: info: BOOT COMPLETED
May 17 16:12:32 volumio volumio[1267]:  [Metrics]  CommandRouter:  70s 60.83ms 
May 17 16:12:32 volumio volumio[1267]: info: CoreCommandRouter::volumiosetStartupVolume
May 17 16:12:32 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 17 16:12:32 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:32 volumio volumio[1267]: info: CoreCommandRouter::Close All Modals sent
May 17 16:12:33 volumio volumio[1267]: info: CoreCommandRouter::Close All Modals sent
May 17 16:12:33 volumio volumio[1267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
May 17 16:12:33 volumio RoonBridge[1850]: 00:00:45.639 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:33 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:33 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:33 volumio RoonBridge[1850]: 00:00:45.674 Debug: PathForResource, filename: ../.update
May 17 16:12:33 volumio RoonBridge[1850]: 00:00:45.674 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:33 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:33 volumio volumio[1267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10
May 17 16:12:33 volumio volumio[1267]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
May 17 16:12:33 volumio volumio[1267]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card
May 17 16:12:33 volumio volumio[1267]: aplay: main:828: audio open error: No such device
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:34 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 17 16:12:34 volumio volumio[1267]: info: Setting Geolocation for MyVolumio to us2
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:34 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:35 volumio volumio[1267]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 17 16:12:35 volumio RoonBridge[1850]: 00:00:47.675 Info:  ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 17 16:12:35 volumio RoonBridge[1850]: Running
May 17 16:12:35 volumio volumio[1267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
May 17 16:12:35 volumio volumio[1267]: info: Updating MyVolumio device info
May 17 16:12:35 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 17 16:12:36 volumio sudo[2393]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 17 16:12:36 volumio sudo[2393]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:36 volumio sudo[2393]: pam_unix(sudo:session): session closed for user root
May 17 16:12:36 volumio jellyfin[1819]: [16:12:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0
May 17 16:12:36 volumio jellyfin[1819]: [16:12:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0
May 17 16:12:36 volumio sudo[2395]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 17 16:12:36 volumio sudo[2395]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:36 volumio jellyfin[1819]: [16:12:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0
May 17 16:12:36 volumio sudo[2395]: pam_unix(sudo:session): session closed for user root
May 17 16:12:36 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164 from 192.168.31.108 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; 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 17 16:12:36 volumio volumio[1267]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 17 16:12:36 volumio volumio[1773]: ...........................................++++
May 17 16:12:36 volumio volumio[1773]: e is 65537 (0x010001)
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 17 16:12:36 volumio volumio[1773]: writing RSA key
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:36 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 17 16:12:36 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:36 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:36 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:36 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:36 volumio volumio[1267]: info: Listing playlists
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 17 16:12:36 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 17 16:12:37 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 17 16:12:37 volumio jellyfin[1819]: [16:12:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0
May 17 16:12:37 volumio jellyfin[1819]: [16:12:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0
May 17 16:12:37 volumio sudo[2423]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 17 16:12:37 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:37 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root
May 17 16:12:38 volumio sudo[2426]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 17 16:12:38 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 17 16:12:38 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root
May 17 16:12:38 volumio volumio[1267]: verbose: New Socket.io Connection to 192.168.31.164 from 192.168.31.108 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; 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 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:38 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 17 16:12:38 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:38 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:38 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:38 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:38 volumio volumio[1267]: info: Listing playlists
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 17 16:12:38 volumio jellyfin[1819]: [16:12:38] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"]
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 17 16:12:38 volumio jellyfin[1819]: [16:12:38] [INF] [1] Main: Kestrel is listening on 0.0.0.0
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:38 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:38 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:38 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 17 16:12:39 volumio jellyfin[1819]: [16:12:39] [WRN] [1] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager: No XML encryptor configured. Key {eabd42b9-5f60-4227-930c-9c120ff36643} may be persisted to storage in unencrypted form.
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 17 16:12:39 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:39 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: Listing playlists
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 17 16:12:39 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:39 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 17 16:12:39 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:39 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: Listing playlists
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 17 16:12:39 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:39 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 17 16:12:39 volumio volumio[1267]: info: Received Get System Info
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 17 16:12:39 volumio volumio[1267]: info: Discovery: Getting this device information
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::volumioGetState
May 17 16:12:39 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 17 16:12:39 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 17 16:12:40 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::pause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::stPlaybackTimer
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::servicePause
May 17 16:12:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::servicePause
May 17 16:12:41 volumio volumio[1267]: info: ControllerMpd::pause
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand pause
May 17 16:12:41 volumio volumio[1267]: info:
May 17 16:12:41 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:41 volumio volumio[1267]: info: sendMpdCommand pause took 8 milliseconds
May 17 16:12:41 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:41 volumio volumio[1267]: info:
May 17 16:12:41 volumio volumio[1267]: ---------------------------- MPD announces state update: player
May 17 16:12:41 volumio volumio[1267]: info: ControllerMpd::getState
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status
May 17 16:12:41 volumio volumio[1267]: info: sendMpdCommand status took 21 milliseconds
May 17 16:12:41 volumio volumio[1267]: info: sendMpdCommand status took 7 milliseconds
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::parseState
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 17 16:12:41 volumio volumio[1267]: info: sendMpdCommand playlistinfo took 10 milliseconds
May 17 16:12:41 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo
May 17 16:12:41 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:41 volumio volumio[1267]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37951,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"838 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:41 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService pause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus pause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::stPlaybackTimer
May 17 16:12:41 volumio volumio[1267]: info: ControllerMpd::pushState
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::servicePushState
May 17 16:12:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:41 volumio volumio[1267]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37951,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"838 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 17 16:12:41 volumio volumio[1267]: verbose: CURRENT POSITION 0
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::syncState   stateService pause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::syncState   currentStatus pause
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::pushState
May 17 16:12:41 volumio volumio[1267]: info: CorePlayQueue::getTrack 0
May 17 16:12:41 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState
May 17 16:12:41 volumio volumio[1267]: info: CoreStateMachine::stPlaybackTimer
May 17 16:12:41 volumio volumio[1267]: info: ------------------------------ 170ms
May 17 16:12:41 volumio volumio[1267]: info: ------------------------------ 155ms
May 17 16:12:41 volumio volumio[1267]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 17 16:12:41 volumio volumio[1267]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
May 17 16:12:41 volumio volumio[1267]:     at Object.readdirSync (fs.js:1048:3)
May 17 16:12:41 volumio volumio[1267]:     at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
May 17 16:12:41 volumio volumio[1267]:     at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
May 17 16:12:41 volumio volumio[1267]:     at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
May 17 16:12:41 volumio volumio[1267]:     at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
May 17 16:12:41 volumio volumio[1267]:     at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
May 17 16:12:41 volumio volumio[1267]:     at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 17 16:12:41 volumio volumio[1267]:     at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
May 17 16:12:41 volumio volumio[1267]:     at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
May 17 16:12:41 volumio volumio[1267]:     at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 17 16:12:41 volumio volumio[1267]:     at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 17 16:12:41 volumio volumio[1267]:     at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
May 17 16:12:41 volumio volumio[1267]:     at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 17 16:12:41 volumio volumio[1267]:     at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 17 16:12:41 volumio volumio[1267]:     at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
May 17 16:12:41 volumio volumio[1267]:     at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
May 17 16:12:41 volumio volumio[1267]:   errno: -20,
May 17 16:12:41 volumio volumio[1267]:   syscall: 'scandir',
May 17 16:12:41 volumio volumio[1267]:   code: 'ENOTDIR',
May 17 16:12:41 volumio volumio[1267]:   path: '/tmp/peppyrunning'
May 17 16:12:41 volumio volumio[1267]: }
May 17 16:12:41 volumio volumio[1267]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 17 16:12:41 volumio RoonBridge[1850]: 00:00:53.707 Warn:  exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 17 16:12:41 volumio RoonBridge[1850]:   at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 17 16:12:41 volumio RoonBridge[1850]:   at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 
May 17 16:12:41 volumio RoonBridge[1850]: 00:00:53.723 Debug: PathForResource, filename: ../.update
May 17 16:12:41 volumio RoonBridge[1850]: 00:00:53.724 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 17 16:12:41 volumio RoonBridge[1850]: Not Running (.o)
May 17 16:12:42 volumio sudo[2520]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 16:11
May 17 16:12:42 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0)
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="bba9e3185e150dd0995f9a49355b34fc6218d5a2"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST"
VOLUMIO_VERSION="3.757"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"