Nov 24 18:48:03 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 56 Nov 24 18:48:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 55 Nov 24 18:48:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 56 Nov 24 18:48:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 57 Nov 24 18:48:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 58 Nov 24 18:48:13 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 57 Nov 24 18:48:18 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 56 Nov 24 18:48:23 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 55 Nov 24 18:48:23 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 56 Nov 24 18:48:23 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 57 Nov 24 18:48:28 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 52 Nov 24 18:48:33 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 52 Nov 24 18:48:33 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 53 Nov 24 18:48:38 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 52 Nov 24 18:48:38 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 53 Nov 24 18:48:43 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 43 Nov 24 18:48:43 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 44 Nov 24 18:48:43 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 45 Nov 24 18:48:43 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 46 Nov 24 18:48:48 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 46 Nov 24 18:48:48 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 47 Nov 24 18:48:48 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 48 Nov 24 18:48:48 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 49 Nov 24 18:48:53 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 48 Nov 24 18:48:53 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 49 Nov 24 18:48:58 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 48 Nov 24 18:48:58 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 49 Nov 24 18:48:58 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 50 Nov 24 18:49:02 bookworm-rpi5 sshd[11910]: Accepted password for volumio from 192.168.1.49 port 50508 ssh2 Nov 24 18:49:02 bookworm-rpi5 sshd[11910]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Nov 24 18:49:02 bookworm-rpi5 systemd-logind[880]: New session 1 of user volumio. Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Starting user@1000.service - User Manager for UID 1000... Nov 24 18:49:02 bookworm-rpi5 (systemd)[11927]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Queued start job for default target default.target. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Created slice app.slice - User Application Slice. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Created slice session.slice - User Core Session Slice. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Reached target paths.target - Paths. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Reached target timers.target - Timers. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on pulseaudio.socket - Sound System. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Reached target sockets.target - Sockets. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Reached target basic.target - Basic System. Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Started user@1000.service - User Manager for UID 1000. Nov 24 18:49:02 bookworm-rpi5 systemd[11927]: Starting pulseaudio.service - Sound Service... Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Started session-1.scope - Session 1 of User volumio. Nov 24 18:49:02 bookworm-rpi5 sshd[11910]: pam_env(sshd:session): deprecated reading of user environment enabled Nov 24 18:49:02 bookworm-rpi5 dbus-daemon[873]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.89' (uid=1000 pid=11944 comm="/usr/bin/pulseaudio --daemonize=no --log-target=jo") Nov 24 18:49:02 bookworm-rpi5 systemd[1]: Starting rtkit-daemon.service - RealtimeKit Scheduling Policy Service... Nov 24 18:49:03 bookworm-rpi5 dbus-daemon[873]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 24 18:49:03 bookworm-rpi5 systemd[1]: Started rtkit-daemon.service - RealtimeKit Scheduling Policy Service. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Successfully called chroot. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Successfully dropped privileges. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Successfully limited resources. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Canary thread running. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Running. Nov 24 18:49:03 bookworm-rpi5 rtkit-daemon[11955]: Watchdog thread running. Nov 24 18:49:03 bookworm-rpi5 dbus-daemon[873]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.90' (uid=0 pid=11955 comm="/usr/libexec/rtkit-daemon") Nov 24 18:49:03 bookworm-rpi5 systemd[1]: Starting polkit.service - Authorization Manager... Nov 24 18:49:03 bookworm-rpi5 polkitd[11961]: Started polkitd version 122 Nov 24 18:49:03 bookworm-rpi5 polkitd[11961]: Loading rules from directory /etc/polkit-1/rules.d Nov 24 18:49:03 bookworm-rpi5 polkitd[11961]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 24 18:49:03 bookworm-rpi5 polkitd[11961]: Finished loading, compiling and executing 2 rules Nov 24 18:49:03 bookworm-rpi5 dbus-daemon[873]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 24 18:49:03 bookworm-rpi5 polkitd[11961]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 24 18:49:03 bookworm-rpi5 systemd[1]: Started polkit.service - Authorization Manager. Nov 24 18:49:03 bookworm-rpi5 systemd[11927]: Starting dbus.service - D-Bus User Message Bus... Nov 24 18:49:03 bookworm-rpi5 systemd[11927]: Started dbus.service - D-Bus User Message Bus. Nov 24 18:49:03 bookworm-rpi5 pulseaudio[11944]: Failed to find a working profile. Nov 24 18:49:03 bookworm-rpi5 pulseaudio[11944]: Failed to load module "module-alsa-card" (argument: "device_id="0" name="platform-107c701400.hdmi" card_name="alsa_card.platform-107c701400.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed. Nov 24 18:49:03 bookworm-rpi5 pulseaudio[11944]: Failed to find a working profile. Nov 24 18:49:03 bookworm-rpi5 pulseaudio[11944]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-107c706400.hdmi" card_name="alsa_card.platform-107c706400.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed. Nov 24 18:49:03 bookworm-rpi5 systemd[11927]: Started pulseaudio.service - Sound Service. Nov 24 18:49:03 bookworm-rpi5 systemd[11927]: Reached target default.target - Main User Target. Nov 24 18:49:03 bookworm-rpi5 systemd[11927]: Startup finished in 560ms. Nov 24 18:49:03 bookworm-rpi5 pulseaudio[11944]: Could not find org.bluez.BatteryProviderManager1.RegisterBatteryProvider(), is bluetoothd started with experimental features enabled (-E flag)? Nov 24 18:49:03 bookworm-rpi5 kernel: Bluetooth: RFCOMM TTY layer initialized Nov 24 18:49:03 bookworm-rpi5 kernel: Bluetooth: RFCOMM socket layer initialized Nov 24 18:49:03 bookworm-rpi5 kernel: Bluetooth: RFCOMM ver 1.11 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSink/sbc Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSource/sbc Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSink/sbc_xq_453 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSource/sbc_xq_453 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSink/sbc_xq_512 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSource/sbc_xq_512 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSink/sbc_xq_552 Nov 24 18:49:03 bookworm-rpi5 bluetoothd[872]: Endpoint registered: sender=:1.102 path=/MediaEndpoint/A2DPSource/sbc_xq_552 Nov 24 18:49:03 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 49 Nov 24 18:49:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 49 Nov 24 18:49:08 bookworm-rpi5 volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 50 Nov 24 18:49:09 bookworm-rpi5 sudo[11988]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio.service Nov 24 18:49:09 bookworm-rpi5 sudo[11988]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Nov 24 18:49:09 bookworm-rpi5 systemd[1]: Stopping volumio.service - Volumio Backend Module... Nov 24 18:49:09 bookworm-rpi5 volumio-remote-updater[886]: [2024-11-24 18:49:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 24 18:49:09 bookworm-rpi5 volumio-remote-updater[886]: [2024-11-24 18:49:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 24 18:49:09 bookworm-rpi5 systemd[1]: volumio.service: Deactivated successfully. Nov 24 18:49:09 bookworm-rpi5 systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 24 18:49:09 bookworm-rpi5 systemd[1]: volumio.service: Consumed 44.000s CPU time. Nov 24 18:49:09 bookworm-rpi5 sudo[11988]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:09 bookworm-rpi5 sudo[12024]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl start volumio.service Nov 24 18:49:09 bookworm-rpi5 sudo[12024]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Nov 24 18:49:09 bookworm-rpi5 systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 24 18:49:09 bookworm-rpi5 systemd[1]: Started volumio.service - Volumio Backend Module. Nov 24 18:49:09 bookworm-rpi5 sudo[12024]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:09 bookworm-rpi5 systemd[1]: dynamicswap.service: Deactivated successfully. Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ----- Volumio3 ---- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ----- System startup ---- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: MYVOLUMIO Environment detected Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Plugin folders cleanup Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning into folder /volumio/app/plugins/ Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category audio_interface Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category miscellanea Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category music_service Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category plugins.json Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category system_controller Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category user_interface Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning into folder /data/plugins/ Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category audio_interface Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Scanning category user_interface Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Plugin folders cleanup completed Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ----- Core plugins startup ---- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:09 bookworm-rpi5 volumio[12031]: info: Loading plugins from folder /volumio/app/plugins/ Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Adding plugin upnp to MyMusic Plugins Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugins from folder /data/plugins/ Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "system"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "appearance"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "network"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Refreshing Cached IP Addresses Nov 24 18:49:10 bookworm-rpi5 sudo[12059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 18:49:10 bookworm-rpi5 sudo[12059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:10 bookworm-rpi5 sudo[12061]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 18:49:10 bookworm-rpi5 sudo[12061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:10 bookworm-rpi5 sudo[12059]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:10 bookworm-rpi5 sudo[12061]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "services"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "alsa_controller"... Nov 24 18:49:10 bookworm-rpi5 sudo[12074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 24 18:49:10 bookworm-rpi5 sudo[12074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "wizard"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "networkfs"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Starting Udev Watcher for removable devices Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Ignoring mount for partition: boot Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Ignoring mount for partition: volumio Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Ignoring mount for partition: volumio_data Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "volumio_command_line_client"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "upnp"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: [1732470550251] Starting Upmpd Daemon Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "my_music"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "mpd"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "upnp_browser"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "alarm-clock"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "airplay_emulation"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Starting Shairport Sync Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "last_100"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "webradio"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "i2s_dacs"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: I2S DAC not set, start Auto-detection Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "volumiodiscovery"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** For more information see Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: *** WARNING *** For more information see Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** For more information see Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 18:49:10 bookworm-rpi5 node[12031]: *** WARNING *** For more information see Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Discovery: Started advertising with name: bookworm-rpi5 Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "outputs"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "albumart"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Plugin example_plugin is not enabled Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "inputs"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "updater_comm"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Plugin mpdemulation is not enabled Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "rest_api"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "websocket"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Starting Socket.io Server version 1.7.4 Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "Bluetoothout"... Nov 24 18:49:10 bookworm-rpi5 volumio[12091]: Forking 3 albumart workers Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Applying required configuration parameters for plugin Bluetoothout Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "fusiondsp"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Applying required configuration parameters for plugin fusiondsp Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading plugin "Systeminfo"... Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: Loading i18n strings for locale fr Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: Updating browse sources language Nov 24 18:49:10 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::initPlayerControls Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: Express server listening on port 3000 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: [Metrics] WebUI: 1s 278.85ms Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::resetVolumioState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::getcurrentVolume Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Volumio Network Manager: Network status updated: 2 Nov 24 18:49:11 bookworm-rpi5 volumio[12102]: Starting albumart workers Nov 24 18:49:11 bookworm-rpi5 volumio[12103]: Starting albumart workers Nov 24 18:49:11 bookworm-rpi5 volumio[12101]: Starting albumart workers Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to 192.168.1.23:3000 from 192.168.1.39 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::pushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioPushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::updateTrackBlock Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrackBlock Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.49 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Reloading queue from file Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::pushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioPushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::setRepeat null single undefined Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::pushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioPushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::setRandom null Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreStateMachine::pushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioPushState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Setting Device type: Raspberry PI Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Completed loading Core Plugins Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Preparing to generate the ALSA configuration file Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Reading ALSA contributions from plugins. Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Asound.conf file unchanged, so no further update is needed Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Output device has changed, restarting MPD Nov 24 18:49:11 bookworm-rpi5 sudo[12158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Output device has changed, restarting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 sudo[12158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 sudo[12159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 18:49:11 bookworm-rpi5 sudo[12159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12158]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: ___________ START PLUGINS ___________ Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: ControllerMpd::onStart: Initializing MPD Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Creating MPD Configuration file Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 18:49:11 bookworm-rpi5 sudo[12176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: [1732470551263] CoreMusicLibrary::Adding element Serveurs Média Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 18:49:11 bookworm-rpi5 sudo[12176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 18:49:11 bookworm-rpi5 sudo[12175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12175]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: [1732470551315] CoreMusicLibrary::Adding element Last_100 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: [1732470551316] CoreMusicLibrary::Adding element Webradio Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Initializing BBC Radios Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Loading i18n strings for locale fr Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Loading i18n strings for locale fr Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: FusionDsp - mixtype--------------------- Software Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Volumio Calling Home Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Preparing to generate the ALSA configuration file Nov 24 18:49:11 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: mpd.service: Consumed 1.194s CPU time. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 24 18:49:11 bookworm-rpi5 sudo[12218]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 18:49:11 bookworm-rpi5 sudo[12218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 24 18:49:11 bookworm-rpi5 sudo[12218]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Reading ALSA contributions from plugins. Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Bluetoothout--- snd_aloop is already loaded, skipping modprobe Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: MPD Permissions set Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: MPD Permissions set Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Starting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Starting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 sudo[12232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 18:49:11 bookworm-rpi5 sudo[12232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Starting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 sudo[12234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 18:49:11 bookworm-rpi5 sudo[12234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 18:49:11 bookworm-rpi5 sudo[12237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Asound.conf file unchanged, so no further update is needed Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Output device has changed, restarting MPD Nov 24 18:49:11 bookworm-rpi5 sudo[12243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Output device has changed, restarting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 24 18:49:11 bookworm-rpi5 sudo[12243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 18:49:11 bookworm-rpi5 sudo[12241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: shairport-sync.service: Consumed 6.288s CPU time. Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 18:49:11 bookworm-rpi5 sudo[12241]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: MPD Permissions set Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Starting Shairport Sync Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 24 18:49:11 bookworm-rpi5 sudo[12259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 18:49:11 bookworm-rpi5 sudo[12259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:11 bookworm-rpi5 sudo[12232]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Shairport-Sync Started Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: Error adding Membership: Error: addMembership EINVAL Nov 24 18:49:11 bookworm-rpi5 sudo[12237]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 sudo[12234]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Shairport-Sync Started Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Shairport-Sync Started Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 24 18:49:11 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 24 18:49:11 bookworm-rpi5 sudo[12259]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: adding cb9136bd-5e70-4c85-86fa-eff4cd2bf0ca Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Found device Volumio-rpi4 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connecting to remote: 192.168.1.93 Nov 24 18:49:11 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Shairport-Sync Started Nov 24 18:49:11 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: error: Upnp client error: Error: read ECONNRESET Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 24 18:49:11 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connected to remote: 192.168.1.93 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: adding c8d9e3d6-fa93-49b0-9dc2-577f68689abf Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Found device Integro-office Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connecting to remote: 192.168.1.39 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: adding 89a86619-18ae-4300-a9bd-067abcfbd7d0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Found device Volumio-primo Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connecting to remote: 192.168.1.77 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to 192.168.1.23:3000 from 192.168.1.77 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 24 18:49:11 bookworm-rpi5 sudo[12263]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 18:49:11 bookworm-rpi5 sudo[12263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 24 18:49:11 bookworm-rpi5 sudo[12263]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: adding b1a570d3-0924-4981-8fd1-68bca66a4193 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Found device bookworm-rpi5 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: this is already registered, b1a570d3-0924-4981-8fd1-68bca66a4193 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Found device bookworm-rpi5 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connected to remote: 192.168.1.77 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Discovery: Connected to remote: 192.168.1.39 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: Volumio called home Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to 192.168.1.23:3000 from 192.168.1.93 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to 192.168.1.23:3000 from 192.168.1.39 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:11 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 mpd[12279]: Nov 24 18:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 18:49:12 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 24 18:49:12 bookworm-rpi5 sudo[12159]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:12 bookworm-rpi5 sudo[12243]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:12 bookworm-rpi5 sudo[12176]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: Completed starting Core Plugins Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: ----- MyVolumio plugins startup ---- Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: ------------------------------------------- Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: MPD error: The expression evaluated to a falsy value: Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: assert.ok(self.idling) Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: The expression evaluated to a falsy value: Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: assert.ok(self.idling) Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: MPD error: The expression evaluated to a falsy value: Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: assert.ok(self.idling) Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: The expression evaluated to a falsy value: Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: assert.ok(self.idling) Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: MPD running with PID12279 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: ,establishing connection Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: updateQueue error: null Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: error: updateQueue error: null Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: Received Get System Info Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: Discovery: Getting this device information Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::volumioGetState Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: CorePlayQueue::getTrack 0 Nov 24 18:49:12 bookworm-rpi5 volumio[12031]: info: Listing playlists Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: camilladsp service started and running in background, instance 1 Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: cannot open device 'volumioHw': Device or resource busy Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: cannot open device 'volumioHw': Device or resource busy Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: FusionDsp loaded Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 18:49:13 bookworm-rpi5 sudo[12305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 24 18:49:13 bookworm-rpi5 sudo[12305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: Adding Signal Path Element [object Object] Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: Adding fusiondspeq DSP Signal Path Element Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: FusionDsp - ---- installed callbackRead Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 8 Nov 24 18:49:13 bookworm-rpi5 sudo[12305]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: ReferenceError: selectdsp is not defined Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: at /data/plugins/audio_interface/fusiondsp/index.js:318:55 Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: at FSReqCallback.oncomplete (node:fs:190:23) Nov 24 18:49:13 bookworm-rpi5 volumio[12031]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 18:49:13 bookworm-rpi5 sudo[12074]: pam_unix(sudo:session): session closed for user root Nov 24 18:49:13 bookworm-rpi5 sudo[12325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-24 18:48' Nov 24 18:49:13 bookworm-rpi5 sudo[12325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="63c682653ce156d0a511068b635ad11a88ca34c3" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 22 12:42:05 UTC 2024" VOLUMIO_VERSION="0.018" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7ebd7e4d5f8447adc1b1160a00713215"