-- Logs begin at Fri 2025-01-17 19:14:39 CET, end at Fri 2025-01-17 19:16:02 CET. -- Jan 17 19:15:00 volumio dhcpcd[894]: wlan0: leased 192.168.169.7 for 283824000 seconds Jan 17 19:15:00 volumio wireless.js[791]: trying... Jan 17 19:15:00 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 17 19:15:00 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:00 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:01 volumio dhcpcd[894]: wlan0: adding route to 192.168.169.0/24 Jan 17 19:15:01 volumio avahi-daemon[792]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.169.7. Jan 17 19:15:01 volumio dhcpcd[894]: wlan0: adding default route via 192.168.169.1 Jan 17 19:15:01 volumio avahi-daemon[792]: New relevant interface wlan0.IPv4 for mDNS. Jan 17 19:15:01 volumio avahi-daemon[792]: Registering new address record for 192.168.169.7 on wlan0.IPv4. Jan 17 19:15:01 volumio ntpd[981]: ntpd exiting on signal 15 (Terminated) Jan 17 19:15:01 volumio systemd[1]: Stopping Network Time Service... Jan 17 19:15:01 volumio systemd[1]: ntp.service: Succeeded. Jan 17 19:15:01 volumio systemd[1]: Stopped Network Time Service. Jan 17 19:15:01 volumio systemd[1]: Starting Network Time Service... Jan 17 19:15:01 volumio ntpd[1161]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 17 19:15:01 volumio ntpd[1161]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 17 19:15:01 volumio systemd[1]: Started Network Time Service. Jan 17 19:15:01 volumio ntpd[1167]: proto: precision = 0.072 usec (-24) Jan 17 19:15:01 volumio ntpd[1167]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 17 19:15:01 volumio ntpd[1167]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 17 19:15:01 volumio ntpd[1167]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 752 days ago Jan 17 19:15:01 volumio ntpd[1167]: Listen and drop on 0 v6wildcard [::]:123 Jan 17 19:15:01 volumio ntpd[1167]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 17 19:15:01 volumio ntpd[1167]: Listen normally on 2 lo 127.0.0.1:123 Jan 17 19:15:01 volumio ntpd[1167]: Listen normally on 3 wlan0 192.168.169.7:123 Jan 17 19:15:01 volumio ntpd[1167]: Listening on routing socket on fd #20 for interface updates Jan 17 19:15:01 volumio ntpd[1167]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 17 19:15:01 volumio ntpd[1167]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 17 19:15:01 volumio wireless.js[791]: trying... Jan 17 19:15:01 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 17 19:15:01 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:01 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:01 volumio wireless.js[791]: Connected to: ----Belkin_N+_7BB6DC Jan 17 19:15:01 volumio wireless.js[791]: ---- Jan 17 19:15:01 volumio sudo[1173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:15:01 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:01 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:01 volumio wireless.js[791]: ... joined AP, wlan0 IPv4 is 192.168.169.7, ipV6 is undefined Jan 17 19:15:01 volumio wireless.js[791]: It's done! AP Jan 17 19:15:01 volumio iwconfig[1177]: Error for wireless request "Set Power Management" (8B2C) : Jan 17 19:15:01 volumio iwconfig[1177]: SET failed on device wlan0 ; Operation not supported. Jan 17 19:15:01 volumio systemd[1]: Started Wireless Services. Jan 17 19:15:01 volumio systemd[1]: Started Volumio Backend Module. Jan 17 19:15:01 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Jan 17 19:15:01 volumio systemd[1]: Started Start Volumio Kiosk. Jan 17 19:15:01 volumio systemd[1]: Started Volumio Cpu Tweaker. Jan 17 19:15:01 volumio volumio-cpu-tweak[1182]: Setting RT Priority for mpd Jan 17 19:15:01 volumio volumio-cpu-tweak[1182]: Setting MPD Affinity Jan 17 19:15:01 volumio volumio-cpu-tweak[1182]: pid 1014's current affinity mask: f Jan 17 19:15:01 volumio volumio-cpu-tweak[1182]: pid 1014's new affinity mask: 3 Jan 17 19:15:01 volumio volumio-cpu-tweak[1182]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 17 19:15:01 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 17 19:15:01 volumio startx[1180]: X.Org X Server 1.20.4 Jan 17 19:15:01 volumio startx[1180]: X Protocol Version 11, Revision 0 Jan 17 19:15:01 volumio startx[1180]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jan 17 19:15:01 volumio startx[1180]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jan 17 19:15:01 volumio startx[1180]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd 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 Jan 17 19:15:01 volumio startx[1180]: Build Date: 18 December 2021 09:05:36AM Jan 17 19:15:01 volumio startx[1180]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jan 17 19:15:01 volumio startx[1180]: Current version of pixman: 0.36.0 Jan 17 19:15:01 volumio startx[1180]: Before reporting problems, check http://wiki.x.org Jan 17 19:15:01 volumio startx[1180]: to make sure that you have the latest version. Jan 17 19:15:01 volumio startx[1180]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 17 19:15:01 volumio startx[1180]: (++) from command line, (!!) notice, (II) informational, Jan 17 19:15:01 volumio startx[1180]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 17 19:15:01 volumio startx[1180]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jan 17 19:15:01 2025 Jan 17 19:15:02 volumio startx[1180]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 17 19:15:02 volumio volumio-remote-updater[794]: [2025-01-17 19:15:02] [info] asio async_connect error: system:111 (Connection refused) Jan 17 19:15:02 volumio volumio-remote-updater[794]: [2025-01-17 19:15:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 17 19:15:02 volumio volumio-remote-updater[794]: [2025-01-17 19:15:02] [error] handle_connect error: Underlying Transport Error Jan 17 19:15:02 volumio soundcard-init.sh[1179]: Simple mixer control 'IEC958',0 Jan 17 19:15:02 volumio soundcard-init.sh[1179]: Capabilities: pswitch pswitch-joined Jan 17 19:15:02 volumio soundcard-init.sh[1179]: Playback channels: Mono Jan 17 19:15:02 volumio soundcard-init.sh[1179]: Mono: Playback [on] Jan 17 19:15:02 volumio systemd[1]: soundcard-init.service: Succeeded. Jan 17 19:15:02 volumio kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.PEG0.LCT1], AE_NOT_FOUND (20230628/psargs-330) Jan 17 19:15:02 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.PEG0.PEGP._ON due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:02 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.GFX0.ATPX due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:02 volumio kernel: [drm] PCIE gen 2 link speeds already enabled Jan 17 19:15:02 volumio kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000). Jan 17 19:15:02 volumio kernel: radeon 0000:01:00.0: WB enabled Jan 17 19:15:02 volumio kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 Jan 17 19:15:02 volumio kernel: radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c Jan 17 19:15:02 volumio kernel: radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x0000000000072118 Jan 17 19:15:02 volumio kernel: debugfs: File 'radeon_ring_gfx' in directory '0' already present! Jan 17 19:15:02 volumio kernel: debugfs: File 'radeon_ring_dma1' in directory '0' already present! Jan 17 19:15:02 volumio kernel: [drm] ring test on 0 succeeded in 2 usecs Jan 17 19:15:02 volumio kernel: [drm] ring test on 3 succeeded in 7 usecs Jan 17 19:15:02 volumio kernel: debugfs: File 'radeon_ring_uvd' in directory '0' already present! Jan 17 19:15:02 volumio kernel: [drm] ring test on 5 succeeded in 2 usecs Jan 17 19:15:02 volumio kernel: [drm] UVD initialized successfully. Jan 17 19:15:02 volumio kernel: [drm] ib test on ring 0 succeeded in 0 usecs Jan 17 19:15:02 volumio kernel: [drm] ib test on ring 3 succeeded in 0 usecs Jan 17 19:15:02 volumio ntpd[1167]: Soliciting pool server 185.157.13.96 Jan 17 19:15:03 volumio kernel: [drm] ib test on ring 5 succeeded Jan 17 19:15:03 volumio ntpd[1167]: Soliciting pool server 89.161.47.131 Jan 17 19:15:03 volumio ntpd[1167]: Soliciting pool server 89.161.47.131 Jan 17 19:15:04 volumio volumio-time-update[795]: volumio-time-update-util: Fetching time from Volumio... Jan 17 19:15:04 volumio systemd[1]: Stopping Music Player Daemon... Jan 17 19:15:05 volumio volumio-time-update[795]: volumio-time-update-util: Setting system time to: 2025-01-17 19:15:08 Jan 17 19:15:05 volumio sudo[1317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-17 19:15:08 Jan 17 19:15:05 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:05 volumio dbus-daemon[790]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1318 comm="timedatectl set-time 2025-01-17 19:15:08 ") Jan 17 19:15:05 volumio ntpd[1167]: Soliciting pool server 162.159.200.1 Jan 17 19:15:06 volumio systemd[1]: Starting Time & Date Service... Jan 17 19:15:06 volumio systemd[1]: mpd.service: Succeeded. Jan 17 19:15:06 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 19:15:06 volumio systemd[1]: Starting Music Player Daemon... Jan 17 19:15:06 volumio dbus-daemon[790]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 17 19:15:06 volumio systemd[1]: Started Time & Date Service. Jan 17 19:15:08 volumio systemd-timedated[1319]: Changed local time to Fri Jan 17 19:15:08 2025 Jan 17 19:15:08 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:08 volumio volumio-time-update[795]: volumio-time-update-util: System time updated successfully. Jan 17 19:15:08 volumio systemd[1]: Started Volumio Time Update Utility. Jan 17 19:15:08 volumio sudo[1320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 19:15:08 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:08 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:08 volumio startx[1180]: (II) [KMS] Kernel modesetting enabled. Jan 17 19:15:08 volumio kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=none:owns=io+mem Jan 17 19:15:08 volumio kernel: radeon 0000:01:00.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=none:owns=none Jan 17 19:15:08 volumio mpd[1322]: Jan 17 19:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 19:15:08 volumio systemd[1]: Started Music Player Daemon. Jan 17 19:15:08 volumio mpd_monitor.sh[804]: MPD restarted due to no mpc output. Jan 17 19:15:08 volumio ntpd[1167]: Soliciting pool server 213.135.57.60 Jan 17 19:15:08 volumio ntpd[1167]: Soliciting pool server 195.46.37.22 Jan 17 19:15:08 volumio ntpd[1167]: Soliciting pool server 193.219.28.2 Jan 17 19:15:09 volumio ntpd[1167]: Soliciting pool server 213.222.217.10 Jan 17 19:15:09 volumio ntpd[1167]: Soliciting pool server 54.37.233.160 Jan 17 19:15:09 volumio ntpd[1167]: Soliciting pool server 193.25.222.136 Jan 17 19:15:10 volumio ntpd[1167]: Soliciting pool server 80.68.234.117 Jan 17 19:15:10 volumio ntpd[1167]: Soliciting pool server 78.40.216.213 Jan 17 19:15:11 volumio nmbd[945]: [2025/01/17 19:15:11.307192, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 17 19:15:11 volumio nmbd[945]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 17 19:15:11 volumio systemd[1]: Started Samba NMB Daemon. Jan 17 19:15:11 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 17 19:15:11 volumio ntpd[1167]: Soliciting pool server 193.70.94.182 Jan 17 19:15:11 volumio ntpd[1167]: Soliciting pool server 2606:4700:f1::1 Jan 17 19:15:14 volumio volumio-remote-updater[794]: [2025-01-17 19:15:14] [connect] Successful connection Jan 17 19:15:14 volumio nmbd[945]: [2025/01/17 19:15:14.085985, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 17 19:15:14 volumio nmbd[945]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.169.7 for name WORKGROUP<1d>. Jan 17 19:15:14 volumio nmbd[945]: This response was from IP 192.168.169.2, reporting an IP address of 192.168.169.2. Jan 17 19:15:14 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 17 19:15:14 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jan 17 19:15:14 volumio winbindd[1326]: [2025/01/17 19:15:14.434637, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 17 19:15:14 volumio winbindd[1326]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 17 19:15:14 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:14 volumio volumio[1178]: info: ----- Volumio3 ---- Jan 17 19:15:14 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:14 volumio volumio[1178]: info: ----- System startup ---- Jan 17 19:15:14 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:16 volumio ntpd[1167]: receive: Unexpected origin timestamp 0xeb351cb2.9232bb58 does not match aorg 0000000000.00000000 from server@185.157.13.96 xmt 0xeb351cb4.6d57db42 Jan 17 19:15:16 volumio ntpd[1167]: receive: Unexpected origin timestamp 0xeb351cb2.92114a73 does not match aorg 0000000000.00000000 from server@193.25.222.136 xmt 0xeb351cb4.6e29a2c1 Jan 17 19:15:16 volumio ntpd[1167]: receive: Unexpected origin timestamp 0xeb351cb2.91da2a2f does not match aorg 0000000000.00000000 from server@78.40.216.213 xmt 0xeb351cb4.6f2b5e78 Jan 17 19:15:20 volumio kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.PEG0.LCT1], AE_NOT_FOUND (20230628/psargs-330) Jan 17 19:15:20 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.PEG0.PEGP._ON due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:20 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.GFX0.ATPX due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:20 volumio kernel: [drm] PCIE gen 2 link speeds already enabled Jan 17 19:15:20 volumio kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000). Jan 17 19:15:20 volumio kernel: radeon 0000:01:00.0: WB enabled Jan 17 19:15:20 volumio kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 Jan 17 19:15:20 volumio kernel: radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c Jan 17 19:15:20 volumio kernel: radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x0000000000072118 Jan 17 19:15:20 volumio kernel: debugfs: File 'radeon_ring_gfx' in directory '0' already present! Jan 17 19:15:20 volumio kernel: debugfs: File 'radeon_ring_dma1' in directory '0' already present! Jan 17 19:15:20 volumio kernel: [drm] ring test on 0 succeeded in 2 usecs Jan 17 19:15:20 volumio kernel: [drm] ring test on 3 succeeded in 7 usecs Jan 17 19:15:20 volumio kernel: debugfs: File 'radeon_ring_uvd' in directory '0' already present! Jan 17 19:15:20 volumio kernel: [drm] ring test on 5 succeeded in 2 usecs Jan 17 19:15:20 volumio kernel: [drm] UVD initialized successfully. Jan 17 19:15:20 volumio kernel: [drm] ib test on ring 0 succeeded in 0 usecs Jan 17 19:15:20 volumio kernel: [drm] ib test on ring 3 succeeded in 0 usecs Jan 17 19:15:21 volumio kernel: [drm] ib test on ring 5 succeeded Jan 17 19:15:22 volumio systemd[1]: Stopping Music Player Daemon... Jan 17 19:15:22 volumio systemd[1]: mpd.service: Succeeded. Jan 17 19:15:22 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 19:15:22 volumio systemd[1]: Starting Music Player Daemon... Jan 17 19:15:23 volumio sh[727]: timed out Jan 17 19:15:23 volumio dhcpcd[771]: timed out Jan 17 19:15:23 volumio sh[727]: dhcpcd exited Jan 17 19:15:23 volumio dhcpcd[771]: dhcpcd exited Jan 17 19:15:23 volumio sh[727]: ifup: failed to bring up eth0 Jan 17 19:15:23 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 17 19:15:23 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 17 19:15:24 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 17 19:15:26 volumio sudo[1342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 19:15:26 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:26 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:26 volumio mpd[1346]: Jan 17 19:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 19:15:26 volumio systemd[1]: Started Music Player Daemon. Jan 17 19:15:26 volumio mpd_monitor.sh[804]: MPD restarted due to no mpc output. Jan 17 19:15:29 volumio winbindd[1326]: [2025/01/17 19:15:29.199519, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 17 19:15:29 volumio winbindd[1326]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 17 19:15:29 volumio systemd[1]: Started Samba Winbind Daemon. Jan 17 19:15:29 volumio systemd[1]: Starting Samba SMB Daemon... Jan 17 19:15:29 volumio smbd[1352]: [2025/01/17 19:15:29.530634, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 17 19:15:29 volumio systemd[1]: Started Samba SMB Daemon. Jan 17 19:15:29 volumio smbd[1352]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 17 19:15:29 volumio systemd[1]: Reached target Multi-User System. Jan 17 19:15:29 volumio systemd[1]: Reached target Graphical Interface. Jan 17 19:15:29 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 17 19:15:29 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 17 19:15:29 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 17 19:15:29 volumio systemd[1]: Startup finished in 5.671s (firmware) + 9.195s (loader) + 16.760s (kernel) + 46.828s (userspace) = 1min 18.456s. Jan 17 19:15:30 volumio volumio[1178]: info: MYVOLUMIO Environment detected Jan 17 19:15:30 volumio volumio[1178]: info: Plugin folders cleanup Jan 17 19:15:30 volumio volumio[1178]: info: Scanning into folder /volumio/app/plugins/ Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category audio_interface Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category miscellanea Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category music_service Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category plugins.json Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category system_controller Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category user_interface Jan 17 19:15:30 volumio volumio[1178]: info: Scanning into folder /data/plugins/ Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category audio_interface Jan 17 19:15:30 volumio volumio[1178]: info: Scanning category user_interface Jan 17 19:15:30 volumio volumio[1178]: info: Plugin folders cleanup completed Jan 17 19:15:30 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:30 volumio volumio[1178]: info: ----- Core plugins startup ---- Jan 17 19:15:30 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:30 volumio volumio[1178]: info: Loading plugins from folder /volumio/app/plugins/ Jan 17 19:15:30 volumio volumio[1178]: info: Adding plugin upnp to MyMusic Plugins Jan 17 19:15:30 volumio volumio[1178]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 17 19:15:30 volumio volumio[1178]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 17 19:15:30 volumio volumio[1178]: info: Loading plugins from folder /data/plugins/ Jan 17 19:15:30 volumio volumio[1178]: info: Loading plugin "system"... Jan 17 19:15:30 volumio volumio[1178]: info: Loading plugin "appearance"... Jan 17 19:15:30 volumio kernel: ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.PEG0.LCT1], AE_NOT_FOUND (20230628/psargs-330) Jan 17 19:15:30 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.PEG0.PEGP._ON due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:30 volumio kernel: ACPI Error: Aborting method \_SB.PCI0.GFX0.ATPX due to previous error (AE_NOT_FOUND) (20230628/psparse-529) Jan 17 19:15:30 volumio kernel: [drm] PCIE gen 2 link speeds already enabled Jan 17 19:15:30 volumio kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000162000). Jan 17 19:15:30 volumio kernel: radeon 0000:01:00.0: WB enabled Jan 17 19:15:30 volumio kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 Jan 17 19:15:30 volumio kernel: radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c Jan 17 19:15:30 volumio kernel: radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x0000000000072118 Jan 17 19:15:30 volumio kernel: debugfs: File 'radeon_ring_gfx' in directory '0' already present! Jan 17 19:15:30 volumio kernel: debugfs: File 'radeon_ring_dma1' in directory '0' already present! Jan 17 19:15:30 volumio kernel: [drm] ring test on 0 succeeded in 2 usecs Jan 17 19:15:30 volumio kernel: [drm] ring test on 3 succeeded in 8 usecs Jan 17 19:15:30 volumio kernel: debugfs: File 'radeon_ring_uvd' in directory '0' already present! Jan 17 19:15:30 volumio volumio-remote-updater[794]: [2025-01-17 19:15:30] [connect] Successful connection Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "network"... Jan 17 19:15:31 volumio volumio[1178]: info: Refreshing Cached IP Addresses Jan 17 19:15:31 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:15:31 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:31 volumio kernel: [drm] ring test on 5 succeeded in 2 usecs Jan 17 19:15:31 volumio kernel: [drm] UVD initialized successfully. Jan 17 19:15:31 volumio kernel: [drm] ib test on ring 0 succeeded in 0 usecs Jan 17 19:15:31 volumio kernel: [drm] ib test on ring 3 succeeded in 0 usecs Jan 17 19:15:31 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:15:31 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:31 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "services"... Jan 17 19:15:31 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "alsa_controller"... Jan 17 19:15:31 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 17 19:15:31 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:31 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "wizard"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "networkfs"... Jan 17 19:15:31 volumio volumio[1178]: info: Starting Udev Watcher for removable devices Jan 17 19:15:31 volumio volumio[1178]: info: Ignoring mount for partition: boot Jan 17 19:15:31 volumio volumio[1178]: info: Ignoring mount for partition: volumio Jan 17 19:15:31 volumio volumio[1178]: info: Ignoring mount for partition: volumio_data Jan 17 19:15:31 volumio volumio[1178]: info: Ignoring mount for partition: Zastrzeżone_przez_system Jan 17 19:15:31 volumio volumio[1178]: info: Ignoring mount for partition: B46AA8996AA859BE Jan 17 19:15:31 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "volumio_command_line_client"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "upnp"... Jan 17 19:15:31 volumio volumio[1178]: info: [1737137731123] Starting Upmpd Daemon Jan 17 19:15:31 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "my_music"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "mpd"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "upnp_browser"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "alarm-clock"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "airplay_emulation"... Jan 17 19:15:31 volumio volumio[1178]: info: Starting Shairport Sync Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "last_100"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "webradio"... Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "i2s_dacs"... Jan 17 19:15:31 volumio volumio[1178]: info: I2S DAC not set, start Auto-detection Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "volumiodiscovery"... Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** For more information see Jan 17 19:15:31 volumio node[1178]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:15:31 volumio volumio[1178]: *** WARNING *** For more information see Jan 17 19:15:31 volumio node[1178]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:15:31 volumio node[1178]: *** WARNING *** For more information see Jan 17 19:15:31 volumio node[1178]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 19:15:31 volumio node[1178]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:15:31 volumio node[1178]: *** WARNING *** For more information see Jan 17 19:15:31 volumio volumio[1178]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 17 19:15:31 volumio volumio[1178]: info: Discovery: Started advertising with name: Volumio Jan 17 19:15:31 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:15:31 volumio volumio[1178]: info: Loading plugin "now_playing"... Jan 17 19:15:31 volumio kernel: [drm] ib test on ring 5 succeeded Jan 17 19:15:32 volumio startx[1180]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jan 17 19:15:33 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:39 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 17 19:15:40 volumio volumio[1178]: info: Loading plugin "outputs"... Jan 17 19:15:40 volumio volumio[1178]: info: Loading plugin "albumart"... Jan 17 19:15:40 volumio volumio[1178]: info: Plugin example_plugin is not enabled Jan 17 19:15:40 volumio volumio[1178]: info: Loading plugin "inputs"... Jan 17 19:15:40 volumio volumio[1178]: info: Loading plugin "updater_comm"... Jan 17 19:15:40 volumio volumio[1178]: Forking 3 albumart workers Jan 17 19:15:41 volumio volumio[1178]: Starting albumart workers Jan 17 19:15:41 volumio volumio[1178]: Starting albumart workers Jan 17 19:15:41 volumio volumio[1178]: Starting albumart workers Jan 17 19:15:41 volumio volumio[1178]: info: Plugin mpdemulation is not enabled Jan 17 19:15:41 volumio volumio[1178]: info: Loading plugin "rest_api"... Jan 17 19:15:41 volumio volumio[1178]: info: Loading plugin "websocket"... Jan 17 19:15:41 volumio volumio[1178]: info: Starting Socket.io Server version 2.3.0 Jan 17 19:15:41 volumio volumio[1178]: info: Loading plugin "fusiondsp"... Jan 17 19:15:43 volumio volumio[1178]: info: Applying required configuration parameters for plugin fusiondsp Jan 17 19:15:43 volumio volumio[1178]: info: Loading i18n strings for locale pl Jan 17 19:15:43 volumio volumio[1178]: Updating browse sources language Jan 17 19:15:43 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::initPlayerControls Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: Express server listening on port 3000 Jan 17 19:15:44 volumio volumio[1178]: [Metrics] WebUI: 38s 289.02ms Jan 17 19:15:44 volumio volumio[1178]: info: Setting Device type: x86 Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::resetVolumioState Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::getcurrentVolume Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:44 volumio sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:15:44 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio volumio[1178]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 17 19:15:44 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:15:44 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio volumio[1178]: info: Completed loading Core Plugins Jan 17 19:15:44 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio volumio[1178]: info: Preparing to generate the ALSA configuration file Jan 17 19:15:44 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio volumio[1178]: info: Cannot read play queue from file Jan 17 19:15:44 volumio volumio[1178]: info: Volumio Network Manager: Network status updated: 2 Jan 17 19:15:44 volumio volumio[1178]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 17 19:15:44 volumio volumio[1178]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 17 19:15:44 volumio volumio[1178]: info: Reading ALSA contributions from plugins. Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: VolumeController:: Volume=41 Mute =false Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::pushState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::updateTrackBlock Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrackBlock Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::setRepeat null single undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::pushState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::setRandom null Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::pushState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jan 17 19:15:44 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:44 volumio volumio[1178]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 17 19:15:44 volumio volumio[1178]: info: VolumeController:: Volume=41 Mute =false Jan 17 19:15:44 volumio volumio[1178]: info: CoreStateMachine::pushState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jan 17 19:15:44 volumio volumio[1178]: info: Asound.conf file unchanged, so no further update is needed Jan 17 19:15:44 volumio volumio[1178]: info: Output device has changed, restarting MPD Jan 17 19:15:44 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 17 19:15:44 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 19:15:44 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio volumio[1178]: info: Output device has changed, restarting Shairport Sync Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:15:44 volumio volumio[1178]: info: ___________ START PLUGINS ___________ Jan 17 19:15:44 volumio volumio[1178]: info: ControllerMpd::onStart: Initializing MPD Jan 17 19:15:44 volumio volumio[1178]: info: Creating MPD Configuration file Jan 17 19:15:44 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 17 19:15:44 volumio sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 19:15:44 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:15:44 volumio systemd[1]: Stopping Music Player Daemon... Jan 17 19:15:44 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:15:44 volumio volumio[1178]: info: [1737137744775] CoreMusicLibrary::Adding element Serwery Mediów Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 19:15:44 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio systemd[1]: mpd.service: Succeeded. Jan 17 19:15:44 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 19:15:44 volumio systemd[1]: Starting Music Player Daemon... Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:44 volumio sudo[1571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 19:15:44 volumio sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:44 volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:15:44 volumio volumio[1178]: info: [1737137744816] CoreMusicLibrary::Adding element Last_100 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:15:44 volumio volumio[1178]: info: [1737137744817] CoreMusicLibrary::Adding element Webradio Jan 17 19:15:44 volumio sudo[1571]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:15:44 volumio volumio[1178]: info: Initializing BBC Radios Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: [now-playing] ConfigUpdater: config is up to date. Jan 17 19:15:44 volumio volumio[1178]: info: Loading i18n strings for locale pl Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: FusionDsp - mixtype--------------------- Hardware Jan 17 19:15:44 volumio volumio[1178]: info: Volumio Calling Home Jan 17 19:15:44 volumio volumio[1178]: info: Preparing to generate the ALSA configuration file Jan 17 19:15:44 volumio volumio[1178]: info: [now-playing] App is listening on port 4004. Jan 17 19:15:44 volumio volumio[1178]: warn: [now-playing] /data/INTERNAL/NowPlayingPlugin/My Backgrounds does not exist. MyBackgroundMonitor will not start. Jan 17 19:15:44 volumio volumio[1178]: info: Discovery: adding db590945-5fbf-4bfa-8882-0bd01dc22477 Jan 17 19:15:44 volumio volumio[1178]: info: Discovery: Found device Volumio Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:44 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:44 volumio volumio[1178]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 17 19:15:44 volumio volumio[1178]: info: Reading ALSA contributions from plugins. Jan 17 19:15:44 volumio volumio[1178]: info: MPD Permissions set Jan 17 19:15:44 volumio volumio[1178]: info: MPD Permissions set Jan 17 19:15:44 volumio volumio[1178]: info: Upmpdcli Daemon Started Jan 17 19:15:44 volumio volumio[1178]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:44 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio mpd[1579]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 19:15:45 volumio mpd[1579]: output: No 'audio_output' defined in config file Jan 17 19:15:45 volumio volumio[1178]: info: Starting Shairport Sync Jan 17 19:15:45 volumio volumio[1178]: info: Starting Shairport Sync Jan 17 19:15:45 volumio volumio[1178]: info: Starting Shairport Sync Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:15:45 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:15:45 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:15:45 volumio volumio[1178]: info: Asound.conf file unchanged, so no further update is needed Jan 17 19:15:45 volumio volumio[1178]: info: Output device has changed, restarting MPD Jan 17 19:15:45 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 19:15:45 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio volumio[1178]: info: Output device has changed, restarting Shairport Sync Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:45 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 19:15:45 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:15:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 19:15:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 17 19:15:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 19:15:45 volumio volumio[1178]: info: MPD Permissions set Jan 17 19:15:45 volumio volumio[1178]: info: Volumio called home Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 19:15:45 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 19:15:45 volumio systemd[1]: mpd.service: Succeeded. Jan 17 19:15:45 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 19:15:45 volumio volumio[1178]: info: Shairport-Sync Started Jan 17 19:15:45 volumio volumio[1178]: Error adding Membership: Error: addMembership EINVAL Jan 17 19:15:45 volumio volumio[1178]: info: Shairport-Sync Started Jan 17 19:15:45 volumio volumio[1178]: info: Shairport-Sync Started Jan 17 19:15:45 volumio volumio[1178]: info: Starting Shairport Sync Jan 17 19:15:45 volumio systemd[1]: Starting Music Player Daemon... Jan 17 19:15:45 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:15:45 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 19:15:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 17 19:15:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 19:15:45 volumio sudo[1630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 19:15:45 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:45 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 19:15:45 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio volumio[1178]: info: Shairport-Sync Started Jan 17 19:15:45 volumio mpd[1638]: Jan 17 19:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 19:15:45 volumio systemd[1]: Started Music Player Daemon. Jan 17 19:15:45 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root Jan 17 19:15:45 volumio volumio[1178]: info: Completed starting Core Plugins Jan 17 19:15:45 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:45 volumio volumio[1178]: info: ----- MyVolumio plugins startup ---- Jan 17 19:15:45 volumio volumio[1178]: info: ------------------------------------------- Jan 17 19:15:45 volumio volumio[1178]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 17 19:15:45 volumio volumio[1178]: info: MPD running with PID1638 Jan 17 19:15:45 volumio volumio[1178]: ,establishing connection Jan 17 19:15:45 volumio volumio[1178]: error: MPD error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: MPD error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: MPD error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: The expression evaluated to a falsy value: Jan 17 19:15:45 volumio volumio[1178]: assert.ok(self.idling) Jan 17 19:15:45 volumio volumio[1178]: error: updateQueue error: null Jan 17 19:15:45 volumio volumio[1557]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 17 19:15:45 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 17 19:15:45 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:15:45 volumio volumio[1178]: info: Discovery: Getting this device information Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:15:45 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:45 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 17 19:15:45 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:45 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio volumio[1178]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 17 19:15:45 volumio volumio[1178]: info: Listing playlists Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreStateMachine::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getQueue Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:15:45 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 17 19:15:45 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 17 19:15:45 volumio volumio[1557]: ..........................++++ Jan 17 19:15:45 volumio volumio-remote-updater[794]: [2025-01-17 19:15:45] [connect] Successful connection Jan 17 19:15:45 volumio volumio-remote-updater[794]: [2025-01-17 19:15:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737137745 101 Jan 17 19:15:45 volumio volumio[1178]: 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: 8 Jan 17 19:15:46 volumio volumio[1557]: ...............................................................++++ Jan 17 19:15:46 volumio volumio[1557]: e is 65537 (0x010001) Jan 17 19:15:46 volumio volumio[1557]: writing RSA key Jan 17 19:15:49 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 17 19:15:49 volumio volumio[1178]: verbose: New Socket.io Connection to 192.168.169.7:3000 from 192.168.169.2 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 17 19:15:58 volumio volumio[1178]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 17 19:15:58 volumio volumio[1178]: info: camilladsp service started and running in background, instance 1 Jan 17 19:15:58 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:15:58 volumio volumio[1178]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 17 19:15:58 volumio volumio[1178]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 17 19:15:58 volumio volumio[1178]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 17 19:15:58 volumio volumio[1178]: info: FusionDsp loaded Jan 17 19:15:58 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:15:58 volumio volumio[1178]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 17 19:15:58 volumio volumio[1178]: info: Adding Signal Path Element [object Object] Jan 17 19:15:58 volumio volumio[1178]: info: Adding fusiondspeq DSP Signal Path Element Jan 17 19:15:58 volumio volumio[1178]: info: FusionDsp - ---- installed callbackRead Jan 17 19:15:58 volumio volumio[1178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:15:58 volumio sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 17 19:15:58 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:15:58 volumio volumio[1178]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 17 19:15:58 volumio volumio[1178]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jan 17 19:15:58 volumio volumio[1178]: at onErrorNT (internal/child_process.js:472:16) Jan 17 19:15:58 volumio volumio[1178]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 17 19:15:58 volumio volumio[1178]: at runNextTicks (internal/process/task_queues.js:64:3) Jan 17 19:15:58 volumio volumio[1178]: at listOnTimeout (internal/timers.js:526:9) Jan 17 19:15:58 volumio volumio[1178]: at processTimers (internal/timers.js:500:7) { Jan 17 19:15:58 volumio volumio[1178]: errno: -2, Jan 17 19:15:58 volumio volumio[1178]: code: 'ENOENT', Jan 17 19:15:58 volumio volumio[1178]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 17 19:15:58 volumio volumio[1178]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 17 19:15:58 volumio volumio[1178]: spawnargs: [ Jan 17 19:15:58 volumio volumio[1178]: '-p', Jan 17 19:15:58 volumio volumio[1178]: 9876, Jan 17 19:15:58 volumio volumio[1178]: '-o', Jan 17 19:15:58 volumio volumio[1178]: '/tmp/camilladsp.log', Jan 17 19:15:58 volumio volumio[1178]: '-l', Jan 17 19:15:58 volumio volumio[1178]: 'warn', Jan 17 19:15:58 volumio volumio[1178]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 17 19:15:58 volumio volumio[1178]: ] Jan 17 19:15:58 volumio volumio[1178]: } Jan 17 19:15:58 volumio volumio[1178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:16:02 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:02 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 19:15 Jan 17 19:16:02 volumio sudo[1769]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"