Feb 18 22:11:35 volumiobookworm sshd[5569]: Accepted publickey for volumio from 192.168.178.23 port 64627 ssh2: ED25519 SHA256:MRmfae8UDl0FrBuOyt4Ir1+2MvnJ7yd4ASxWwXxRhCU Feb 18 22:11:35 volumiobookworm sshd[5569]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 18 22:11:35 volumiobookworm systemd-logind[875]: New session 12 of user volumio. Feb 18 22:11:35 volumiobookworm systemd[1]: Started session-12.scope - Session 12 of User volumio. Feb 18 22:11:35 volumiobookworm sshd[5569]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 18 22:11:36 volumiobookworm wpa_supplicant[1157]: wlan0: WPA: Group rekeying completed with 1c:ed:6f:da:9a:b3 [GTK=CCMP] Feb 18 22:11:41 volumiobookworm sudo[5592]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-plugins-sources-bookworm/serialampcontroller ; USER=root ; COMMAND=/bin/systemctl stop volumio.service Feb 18 22:11:41 volumiobookworm sudo[5592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:41 volumiobookworm systemd[1]: Stopping volumio.service - Volumio Backend Module... Feb 18 22:11:41 volumiobookworm volumio-remote-updater[881]: [2025-02-18 22:11:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 22:11:41 volumiobookworm volumio-remote-updater[881]: [2025-02-18 22:11:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 22:11:41 volumiobookworm systemd[1]: volumio.service: Deactivated successfully. Feb 18 22:11:41 volumiobookworm systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 22:11:41 volumiobookworm systemd[1]: volumio.service: Consumed 51.355s CPU time. Feb 18 22:11:41 volumiobookworm sudo[5592]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:41 volumiobookworm sudo[5619]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-plugins-sources-bookworm/serialampcontroller ; USER=root ; COMMAND=/bin/systemctl start volumio.service Feb 18 22:11:41 volumiobookworm sudo[5619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:41 volumiobookworm systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 22:11:41 volumiobookworm systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 22:11:41 volumiobookworm sudo[5619]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:41 volumiobookworm systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ----- Volumio3 ---- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ----- System startup ---- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: MYVOLUMIO Environment detected Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Plugin folders cleanup Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning into folder /volumio/app/plugins/ Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category audio_interface Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category miscellanea Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category music_service Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category plugins.json Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category system_controller Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category user_interface Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning into folder /data/plugins/ Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Scanning category system_hardware Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Plugin folders cleanup completed Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ----- Core plugins startup ---- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Adding plugin upnp to MyMusic Plugins Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugins from folder /data/plugins/ Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "system"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "appearance"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "network"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Refreshing Cached IP Addresses Feb 18 22:11:41 volumiobookworm sudo[5653]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 22:11:41 volumiobookworm sudo[5653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:41 volumiobookworm sudo[5655]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 22:11:41 volumiobookworm sudo[5653]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:41 volumiobookworm sudo[5655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:41 volumiobookworm sudo[5655]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "services"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "alsa_controller"... Feb 18 22:11:41 volumiobookworm sudo[5664]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 22:11:41 volumiobookworm sudo[5664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:41 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "wizard"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "networkfs"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Starting Udev Watcher for removable devices Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Ignoring mount for partition: boot Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Ignoring mount for partition: volumio Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Ignoring mount for partition: volumio_data Feb 18 22:11:41 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "volumio_command_line_client"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "upnp"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: [1739913101950] Starting Upmpd Daemon Feb 18 22:11:41 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "my_music"... Feb 18 22:11:41 volumiobookworm volumio[5625]: info: Loading plugin "mpd"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "upnp_browser"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "alarm-clock"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "airplay_emulation"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Starting Shairport Sync Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "last_100"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "webradio"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "i2s_dacs"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "volumiodiscovery"... Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** For more information see Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 22:11:42 volumiobookworm volumio[5625]: *** WARNING *** For more information see Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** For more information see Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 22:11:42 volumiobookworm node[5625]: *** WARNING *** For more information see Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Discovery: Started advertising with name: VolumioBookworm Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "outputs"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "albumart"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Plugin example_plugin is not enabled Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "inputs"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "updater_comm"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Plugin mpdemulation is not enabled Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "rest_api"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading plugin "websocket"... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Starting Socket.io Server version 1.7.4 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Plugin rotaryencoder2 is not enabled Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Plugin serialampcontroller is not enabled Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Loading i18n strings for locale de Feb 18 22:11:42 volumiobookworm volumio[5625]: Updating browse sources language Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::initPlayerControls Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: Express server listening on port 3000 Feb 18 22:11:42 volumiobookworm volumio[5625]: [Metrics] WebUI: 0s 985.05ms Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::resetVolumioState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::getcurrentVolume Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Volumio Network Manager: Network status updated: 2 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::pushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioPushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::updateTrackBlock Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrackBlock Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Reloading queue from file Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::pushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioPushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Setting Device type: Raspberry PI Feb 18 22:11:42 volumiobookworm volumio[5625]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 18 22:11:42 volumiobookworm volumio[5625]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 18 22:11:42 volumiobookworm volumio[5697]: Forking 3 albumart workers Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::setRepeat false single undefined Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::pushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioPushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::setRandom false Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreStateMachine::pushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioPushState Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Completed loading Core Plugins Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Preparing to generate the ALSA configuration file Feb 18 22:11:42 volumiobookworm volumio[5625]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Reading ALSA contributions from plugins. Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Asound.conf file unchanged, so no further update is needed Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Output device has changed, restarting MPD Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Output device has changed, restarting Shairport Sync Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm sudo[5762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 22:11:42 volumiobookworm sudo[5762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:42 volumiobookworm sudo[5760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 22:11:42 volumiobookworm sudo[5760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:42 volumiobookworm sudo[5760]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: ___________ START PLUGINS ___________ Feb 18 22:11:42 volumiobookworm systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: ControllerMpd::onStart: Initializing MPD Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Creating MPD Configuration file Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 22:11:42 volumiobookworm volumio[5625]: info: [1739913102589] CoreMusicLibrary::Adding element Medienserver Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 22:11:42 volumiobookworm sudo[5770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 22:11:42 volumiobookworm sudo[5770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:42 volumiobookworm systemd[1]: mpd.service: Deactivated successfully. Feb 18 22:11:42 volumiobookworm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 22:11:42 volumiobookworm systemd[1]: mpd.socket: Deactivated successfully. Feb 18 22:11:42 volumiobookworm sudo[5770]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:42 volumiobookworm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 22:11:42 volumiobookworm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm sudo[5772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 22:11:42 volumiobookworm sudo[5772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 22:11:42 volumiobookworm volumio[5625]: info: [1739913102621] CoreMusicLibrary::Adding element Last_100 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 22:11:42 volumiobookworm volumio[5625]: info: [1739913102621] CoreMusicLibrary::Adding element Webradio Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Initializing BBC Radios Feb 18 22:11:42 volumiobookworm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 22:11:42 volumiobookworm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 22:11:42 volumiobookworm systemd[1]: mpd.service: Deactivated successfully. Feb 18 22:11:42 volumiobookworm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 22:11:42 volumiobookworm systemd[1]: mpd.socket: Deactivated successfully. Feb 18 22:11:42 volumiobookworm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 22:11:42 volumiobookworm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 22:11:42 volumiobookworm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 22:11:42 volumiobookworm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Volumio Calling Home Feb 18 22:11:42 volumiobookworm sudo[5797]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 22:11:42 volumiobookworm sudo[5797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 22:11:42 volumiobookworm sudo[5797]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:42 volumiobookworm volumio[5731]: Starting albumart workers Feb 18 22:11:42 volumiobookworm volumio[5625]: info: MPD Permissions set Feb 18 22:11:42 volumiobookworm volumio[5625]: info: MPD Permissions set Feb 18 22:11:42 volumiobookworm volumio[5730]: Starting albumart workers Feb 18 22:11:42 volumiobookworm volumio[5625]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5729]: Starting albumart workers Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 22:11:42 volumiobookworm volumio[5625]: info: Starting Shairport Sync Feb 18 22:11:43 volumiobookworm sudo[5830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 22:11:43 volumiobookworm sudo[5830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Starting Shairport Sync Feb 18 22:11:43 volumiobookworm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 22:11:43 volumiobookworm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 22:11:43 volumiobookworm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 22:11:43 volumiobookworm systemd[1]: shairport-sync.service: Consumed 15.712s CPU time. Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Starting Shairport Sync Feb 18 22:11:43 volumiobookworm sudo[5834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 22:11:43 volumiobookworm sudo[5834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:43 volumiobookworm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 22:11:43 volumiobookworm sudo[5836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 22:11:43 volumiobookworm sudo[5836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:43 volumiobookworm sudo[5830]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Volumio called home Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Shairport-Sync Started Feb 18 22:11:43 volumiobookworm volumio[5625]: Error adding Membership: Error: addMembership EINVAL Feb 18 22:11:43 volumiobookworm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 22:11:43 volumiobookworm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 22:11:43 volumiobookworm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 22:11:43 volumiobookworm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 22:11:43 volumiobookworm sudo[5834]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:43 volumiobookworm sudo[5836]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Shairport-Sync Started Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Shairport-Sync Started Feb 18 22:11:43 volumiobookworm mpd[5805]: Feb 18 22:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 22:11:43 volumiobookworm systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 22:11:43 volumiobookworm sudo[5762]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:43 volumiobookworm sudo[5772]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Completed starting Core Plugins Feb 18 22:11:43 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:43 volumiobookworm volumio[5625]: info: ----- MyVolumio plugins startup ---- Feb 18 22:11:43 volumiobookworm volumio[5625]: info: ------------------------------------------- Feb 18 22:11:43 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 22:11:43 volumiobookworm volumio[5625]: error: MPD error: The expression evaluated to a falsy value: Feb 18 22:11:43 volumiobookworm volumio[5625]: assert.ok(self.idling) Feb 18 22:11:43 volumiobookworm volumio[5625]: error: The expression evaluated to a falsy value: Feb 18 22:11:43 volumiobookworm volumio[5625]: assert.ok(self.idling) Feb 18 22:11:43 volumiobookworm volumio[5625]: error: updateQueue error: null Feb 18 22:11:43 volumiobookworm volumio[5625]: info: MPD running with PID5805 Feb 18 22:11:43 volumiobookworm volumio[5625]: ,establishing connection Feb 18 22:11:43 volumiobookworm volumio[5625]: error: updateQueue error: null Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Discovery: adding b2b97e59-ce55-4c37-b4ff-b6944d4743c2 Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Discovery: Found device VolumioBookworm Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Discovery: this is already registered, b2b97e59-ce55-4c37-b4ff-b6944d4743c2 Feb 18 22:11:43 volumiobookworm volumio[5625]: info: Discovery: Found device VolumioBookworm Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:43 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:45 volumiobookworm sudo[5664]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:46 volumiobookworm volumio-remote-updater[881]: [2025-02-18 22:11:46] [connect] Successful connection Feb 18 22:11:46 volumiobookworm volumio-remote-updater[881]: [2025-02-18 22:11:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739913106 101 Feb 18 22:11:46 volumiobookworm volumio[5625]: 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: 2 Feb 18 22:11:48 volumiobookworm volumio[5625]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 22:11:48 volumiobookworm volumio[5625]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 22:11:48 volumiobookworm volumio[5625]: info: Received Get System Info Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 22:11:48 volumiobookworm volumio[5625]: info: Discovery: Getting this device information Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:48 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:48 volumiobookworm volumio[5625]: info: Listing playlists Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 22:11:50 volumiobookworm volumio[5625]: info: Received Get System Info Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 22:11:50 volumiobookworm volumio[5625]: info: Discovery: Getting this device information Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CoreCommandRouter::volumioGetState Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CorePlayQueue::getTrack 0 Feb 18 22:11:50 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 22:11:51 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 22:11:51 volumiobookworm volumio[5625]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 22:11:51 volumiobookworm volumio[5625]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 22:11:51 volumiobookworm volumio[5625]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 22:11:51 volumiobookworm volumio[5625]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 22:11:51 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:51 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:51 volumiobookworm volumio[5625]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 22:11:51 volumiobookworm volumio[5625]: info: MyVolumio login type: Token Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 22:11:51 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 22:11:52 volumiobookworm volumio[5625]: info: Starting Streaming Service Transparent Proxy Feb 18 22:11:52 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 22:11:52 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 22:11:52 volumiobookworm volumio[5625]: info: Streaming services startup Feb 18 22:11:52 volumiobookworm volumio[5625]: info: Starting Streaming Daemon Feb 18 22:11:52 volumiobookworm sudo[5901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 22:11:52 volumiobookworm sudo[5901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:52 volumiobookworm volumio[5625]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 22:11:52 volumiobookworm sudo[5901]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:52 volumiobookworm sudo[5917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 22:11:52 volumiobookworm volumio[5625]: error: Cannot start Volumio Streaming Daemon Feb 18 22:11:52 volumiobookworm volumio[5625]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 22:11:52 volumiobookworm volumio[5625]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 22:11:52 volumiobookworm sudo[5917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:52 volumiobookworm sudo[5919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 22:11:52 volumiobookworm sudo[5919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:52 volumiobookworm sudo[5919]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:52 volumiobookworm sudo[5917]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:52 volumiobookworm sudo[5923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 22:11:52 volumiobookworm sudo[5923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:52 volumiobookworm sudo[5923]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:52 volumiobookworm volumio[5625]: info: Upmpdcli Daemon Started Feb 18 22:11:52 volumiobookworm volumio[5625]: error: MyVolumio Custom Token format not valid, refreshing it Feb 18 22:11:52 volumiobookworm volumio[5899]: STREAMING PROXY: Starting server on port 3245 Feb 18 22:11:52 volumiobookworm volumio[5899]: Node JS runtime: 20 Feb 18 22:11:53 volumiobookworm volumio[5625]: info: MyVolumio login type: Token Feb 18 22:11:53 volumiobookworm volumio[5625]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 18 22:11:54 volumiobookworm volumio[5625]: info: MyVolumio token set successfully Feb 18 22:11:54 volumiobookworm volumio[5625]: info: MYVOLUMIO: Adding device Feb 18 22:11:54 volumiobookworm volumio[5625]: info: MYVOLUMIO: Evaluating Server Feb 18 22:11:54 volumiobookworm volumio[5625]: info: MyVolumio status changed Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Streaming services startup Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Starting Streaming Daemon Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Removing browser output: myVolumio user plan is not superstar Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Removing audio output: Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Stoppping Tunnel 1 Feb 18 22:11:54 volumiobookworm sudo[5943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 22:11:54 volumiobookworm sudo[5943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:54 volumiobookworm sudo[5945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 18 22:11:54 volumiobookworm sudo[5945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm sudo[5943]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:54 volumiobookworm volumio[5625]: error: Cannot start Volumio Streaming Daemon Feb 18 22:11:54 volumiobookworm volumio[5625]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 22:11:54 volumiobookworm volumio[5625]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 22:11:54 volumiobookworm sudo[5945]: pam_unix(sudo:session): session closed for user root Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Remote SSH Stopped Feb 18 22:11:54 volumiobookworm volumio[5625]: info: Setting Geolocation for MyVolumio to eu10 Feb 18 22:11:54 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:54 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:54 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:54 volumiobookworm volumio[5625]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 18 22:11:55 volumiobookworm volumio[5625]: info: Updating MyVolumio device info Feb 18 22:11:55 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:55 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:55 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:55 volumiobookworm volumio[5625]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 18 22:11:58 volumiobookworm volumio[5625]: info: MYVOLUMIO: Adding device Feb 18 22:11:58 volumiobookworm volumio[5625]: info: MYVOLUMIO: Evaluating Server Feb 18 22:11:59 volumiobookworm volumio[5625]: info: Setting Geolocation for MyVolumio to eu6 Feb 18 22:11:59 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:59 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:59 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:11:59 volumiobookworm volumio[5625]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 18 22:12:00 volumiobookworm volumio[5625]: info: Updating MyVolumio device info Feb 18 22:12:00 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:12:00 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:12:00 volumiobookworm volumio[5625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 22:12:00 volumiobookworm volumio[5625]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: Enabling plugin serialampcontroller Feb 18 22:12:02 volumiobookworm volumio[5625]: info: Loading plugin "serialampcontroller"... Feb 18 22:12:02 volumiobookworm volumio[5625]: info: Applying required configuration parameters for plugin serialampcontroller Feb 18 22:12:02 volumiobookworm volumio[5625]: info: Applying required configuration parameters for plugin serialampcontroller Feb 18 22:12:02 volumiobookworm volumio[5625]: info: PLUGIN START: serialampcontroller Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] onStart: config: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"logging":{"type":"boolean","value":true},"serialInterfaceDev":{"type":"string","value":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0"},"ampType":{"type":"string","value":"Rotel - RC-1592 >=V1.53"},"volumioInput":{"type":"string","value":"Opt1"},"minVolume":{"type":"number","value":0},"maxVolume":{"type":"number","value":35},"startupVolume":{"type":"number","value":10},"volumeSteps":{"type":"number","value":1},"mapTo100":{"type":"boolean","value":false},"pauseWhenMuted":{"type":"boolean","value":true},"pauseWhenInputChanged":{"type":"boolean","value":true},"switchInputAtPlay":{"type":"boolean","value":true},"startAtPowerup":{"type":"boolean","value":false},"tcpip":{"type":"boolean","value":true},"ipaddress":{"type":"string","value":"127.0.0.1"}},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/serialampcontroller/config.json"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] loadI18nStrings: /data/plugins/system_hardware/serialampcontroller/i18n/strings_de.json Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] loadI18nStrings: loaded: {"PLUGIN_CONFIGURATION":"Serial Verstärker Steuerung - Konfiguration","SAVE":"Speichern","SERIAL_INTERFACE":"Serielle Schnittstelle","SERIAL_INTERFACE_DEVICE":"Gerät","SET_DEBUG":"Fehlersuche","D_SET_DEBUG":"Einstellungen für die funktionale Fehlersuche.","LOGGING":"Protokoll ausgeben","D_LOGGING":"Schaltet die Ausgabe eines Ablaufprotokolls ein. Fehlermeldungen werden immer ausgegeben.","AMP_SETTINGS":"Verstärker Einstellungen","AMP_TYPE_SECTION":"Verstärker","AMP_TYPE":"Hersteller/Typ","D_AMP_TYPE":"Nach dem Auswählen SPEICHERN drücken, damit zusätzliche Optionen angeboten werden.","VOLUMIO_INPUT":"Volumio Eingang","PAUSE_WHEN_MUTED":"Pausieren bei 'Stumm'","D_PAUSE_WHEN_MUTED":"Pausiert die Wiedergabe in Volumio, wenn der Verstärker 'Stumm' geschaltet wird. Die Wiedergabe wird fortgesetzt, wenn die Stummschaltung beendet wird.","MIN_VOLUME":"Minimale Lautstärke","D_MIN_VOLUME":"Die minimale Lautstärke die von Volumio aus eingestellt werden kann. Über die Bedienelemente des Verstärkers läßt sich weiterhin jede Lautstärke einstellen.","MAX_VOLUME":"Maximale Lautstärke","D_MAX_VOLUME":"Die maximale Lautstärke die von Volumio aus eingestellt werden kann. Über die Bedienelemente des Verstärkers läßt sich weiterhin jede Lautstärke einstellen.","VOLUME_STEPS":"Lautstärke-Schritte pro Klick","D_VOLUME_STEPS":"Die Anzah der Schritte, um die die Lautstärke beim Betätigen der +/- Tasten in Volumio erhöht/vermindert wird. Dies funktioniert nur für Verstärker, die keine dedizierten Kommandos für Lautstärke +/- unterstützen.","PAUSE_WHEN_INPUT_CHANGED":"Pausieren bei Wechsel des Eingangs","D_PAUSE_WHEN_INPUT_CHANGED":"Pausiert die Wiedergabe in Volumio, wenn am Verstärker der Audio-Eingang gewechselt wird. Die Wiedergabe startet wieder, wenn der Eingang zurück auf den ausgewählten Volumio-Eingang geschaltet wird.","SWITCH_INPUT_AT_PLAY":"Verstärkereingang bei Wiedergabe aktivieren","D_SWITCH_INPUT_AT_PLAY":"Aktiviert den Eingang des Verstärkers, an den Volumio angeschlossen ist.","STARTUP_VOLUME":"Start-Lautstärke","D_STARTUP_VOLUME":"Anfangslautstärke beim Start von Volumio und bei der Rückkehr des Verstärkers aus dem Ruhemodus.","TOAST_SAVE_SUCCESS":"Speichern erfolgreich","TOAST_DEBUG_SAVE":"Fehlerprotokoll Einstellungen","TOAST_SERIAL_SAVE":"Einstellungen Serielle Schnittstelle","TOAST_AMP_SAVE":"Einstellungen Verstärker","TOAST_AMP_TYPE_SAVE":"Verstärker Typ","MAP_TO_100":"Lautstärke umrechnen auf 0-100.","D_MAP_TO_100":"Rechnet die Lautstärke-Werte am Verstärker auf 0-100 in Volumio um.","START_AT_POWERUP":"Wiedergabe beim Einschalten des Verstärkers","D_START_AT_POWERUP":"Schaltet den beim Einschalten des Verstärkers den Eingang auf Volumio und beginnt die Wiedergabe.","IP":"IP-Adresse","D_IP":"IP-Adresse des Verstärkers im lokalen Netzwerk","TCPIP":"TCP/IP","D_TCPIP":"TCP/IP Protokoll statt serieller Schnittstelle nutzen.","TOAST_ERROR":"Fehler","TOAST_INVALID_IP":"Ungültige IP-Adresse"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"amps":[{"vendor":"Anthem","model":"IDMSTR IA","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["Coax1","Coax2","Opt1","Opt2","AES","USB","RCA1","RCA2","RCA3","RCA4","PhonoMM","PhonoMC","XLR","XLRARC","Rasp1"],"sourceCmd":["Z1INP01;","Z1INP02;","Z1INP03;","Z1INP04;","Z1INP05;","Z1INP06;","Z1INP07;","Z1INP08;","Z1INP09;","Z1INP10;","Z1INP11;","Z1INP12;","Z1INP13;","Z1INP14;","Z1INP15;"],"sourceRespPostfix":["01","02","03","04","05","06","07","08","09","10","11","12","13","14","15"],"delimiter":";","commands":{"powerOn":"Z1POW1;","powerToggle":"Z1POW0;","volUp":"Z1VUP00.5;","volDown":"Z1VDN00.5;","volValue":"Z1VOL##.0;","mute":"Z1MUTt;","muteOn":"Z1MUT1;","muteOff":"Z1MUT0;"},"responses":[{"rx":"Z1POW1","cmd":["respPowerOn"]},{"rx":"Z1POW0","cmd":["respPowerOff"]},{"rx":"Z1VOL(-[0-9]{2}).([0-5]{1})","cmd":["respVolume"]},{"rx":"Z1MUT1","cmd":["respMuteOn"]},{"rx":"Z1MUT0","cmd":["respMuteOff"]},{"rx":"Z1INP([0-9]{1,2})","cmd":["respSource"]}],"statusRequests":{"reqPower":"Z1POW?;","reqSource":"Z1INP?;","reqVolume":"Z1VOL?;","reqMute":"Z1MUT?;","reqModel":"IDM?;"}},{"vendor":"Rotel","model":"A12","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A14","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A11","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Aux1","Tuner","Phono","Bluetooth"],"sourceCmd":["cd!","aux1!","tuner!","phono!","bluetooth!"],"sourceRespPostfix":["cd","aux1","tuner","phono","bluetooth"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"RC-1570","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","PC-USB","XLR"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","pc_usb!","bal_xlr!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","pc_usb","bal_xlr"],"delimiter":"!","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"volume_up!","volDown":"volume_down!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":""}},{"vendor":"Rotel","model":"RC-1592 >=V1.53","interfaces":["RS232","TCP/IP"],"tcpport":9590,"baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Coax3","Opt1","Opt2","Opt3","Aux","Tuner","Phono","USB","Bluetooth","Bal XLR","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","coax3!","opt1!","opt2!","opt3!","aux!","tuner!","phono!","usb!","bluetooth!","bal_xlr!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","coax3","opt1","opt2","opt3","aux","tuner","phono","usb","bluetooth","bal_xlr","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_123]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":""}},{"vendor":"Rotel","model":"RSP-1582 SW V5.xx","interfaces":["RS232","TCP/IP"],"tcpport":9596,"baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Video 1","Video 2","Video 3","Video 4","Video 5","Video 6","Video 7","Video 8","Tuner","Phono","USB","PC-USB","XLR"],"sourceCmd":["cd!","video1!","video2!","video3!","video4!","video5!","video6!","video7!","video8!","tuner!","phono!","usb!","pc_usb!","bal_xlr!"],"sourceRespPostfix":["cd","video1","video2","video3","video4","video5","video6","video7","video8","tuner","phono","usb","pc_usb","bal_xlr"],"delimiter":"!","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"volume_up!","volDown":"volume_down!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":""}},{"vendor":"Rotel","model":"RA-1592 SW >=V1.52","interfaces":["RS232","TCP/IP"],"tcpport":9590,"baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Coax3","Opt1","Opt2","Opt3","Aux","Tuner","Phono","USB","Bluetooth","Bal XLR","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","coax3!","opt1!","opt2!","opt3!","aux!","tuner!","phono!","usb!","bluetooth!","bal_xlr!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","coax3","opt1","opt2","opt3","aux","tuner","phono","usb","bluetooth","bal_xlr","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Meridian","model":"DSP420","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Radio","LP","TV","Tape 1","Tape 2","CDR","Cable","DVD","VCR 1","VCR 2","Laser Disc"],"sourceCmd":["CD","RD","LP","TV","T1","T2","CR","CB","DV","V1","V2","LD"],"sourceRespPostfix":["CD","Radio","LP","TV","Tape1","Tape2","CDR","Cable","DVD","VCR1","VCR2","LDisc"],"delimiter":"\r\n","commands":{"powerToggle":"SB","volUp":"VP","volDown":"VM","volValue":"VN##!","mute":"MU"},"responses":[{"rx":"([A-Za-z0-9]{2,5}) *([0-9]{1,2})","cmd":["respSource","respVolume","respMuteOff"]},{"rx":"Mute","cmd":["respMuteOn"]},{"rx":"Standby","cmd":["respPowerOff"]}],"statusRequests":{}},{"vendor":"Cambridge","model":"CXA61","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["A1","A2","A3","A4","D1","D2","D3","MP3","Bluetooth","USB"],"sourceCmd":["#03,04,00\r","#03,04,01\r","#03,04,02\r","#03,04,03\r","#03,04,04\r","#03,04,05\r","#03,04,06\r","#03,04,10\r","#03,04,14\r","#03,04,16\r"],"sourceRespPostfix":["00","01","02","03","04","05","06","10","14","16"],"delimiter":"\r","commands":{"powerOn":"#1,02,1\r","muteOn":"#1,04,1\r","muteOff":"#1,04,0\r"},"responses":[{"rx":"#02,01,1","cmd":["respPowerOn"]},{"rx":"#02,01,0","cmd":["respPowerOff"]},{"rx":"#02,03,1","cmd":["respMuteOn"]},{"rx":"#02,03,0","cmd":["respMuteOff"]},{"rx":"#04,01,([0-9]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"#01,01\r","reqSource":"#03,01\r","reqVolume":"","reqMute":"#01,03\r","reqModel":"#13,02\r"}}]},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/serialampcontroller/ampCommands.json"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions for 7(serial) and 3 (TCP/IP) Amplifiers. Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] listSerialDevices: [{"path":"/dev/ttyUSB0","manufacturer":"Prolific Technology Inc. ","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0","vendorId":"067b","productId":"2303"},{"path":"/dev/ttyAMA10"}] Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] listSerialDevices: {"path":"/dev/ttyUSB0","manufacturer":"Prolific Technology Inc. ","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0","vendorId":"067b","productId":"2303"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] listSerialDevices: {"path":"/dev/ttyAMA10"} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] listSerialDevices: found 1 devices.[{"path":"/dev/ttyUSB0","manufacturer":"Prolific Technology Inc. ","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0","vendorId":"067b","productId":"2303"}] Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] setActiveAmp: {"vendor":"Rotel","model":"RC-1592 >=V1.53","interfaces":["RS232","TCP/IP"],"tcpport":9590,"baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Coax3","Opt1","Opt2","Opt3","Aux","Tuner","Phono","USB","Bluetooth","Bal XLR","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","coax3!","opt1!","opt2!","opt3!","aux!","tuner!","phono!","usb!","bluetooth!","bal_xlr!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","coax3","opt1","opt2","opt3","aux","tuner","phono","usb","bluetooth","bal_xlr","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_123]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":""}} Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] setActiveAmp: can send these responses: respPowerOn,respPowerOff,respVolume,respMuteOn,respMuteOff,respSource Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] openSerialPort: starting Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] openSerialPort: check for usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0 in [{"path":"/dev/ttyUSB0","manufacturer":"Prolific Technology Inc. ","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0","vendorId":"067b","productId":"2303"}] Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] openSerialPort: connect to [{"path":"/dev/ttyUSB0","manufacturer":"Prolific Technology Inc. ","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller_D-if00-port0","vendorId":"067b","productId":"2303"}] configured with: {"autoOpen":false,"lock":true,"baudRate":115200,"dataBits":8,"stopBits":1,"parity":"none","rtscts":false,"xon":false,"xoff":false,"xany":false} at /dev/ttyUSB0 Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] openSerialPort: Now trying to open port Feb 18 22:12:02 volumiobookworm volumio[5625]: info: [SERIALAMPCONTROLLER] serial port [event:open]: Port is now open. Connecting Parser with delimiter: $ Feb 18 22:12:02 volumiobookworm volumio[5625]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 22:12:02 volumiobookworm volumio[5625]: TypeError: self.getAmpStatus is not a function Feb 18 22:12:02 volumiobookworm volumio[5625]: at SerialPort. (/data/plugins/system_hardware/serialampcontroller/index.js:550:26) Feb 18 22:12:02 volumiobookworm volumio[5625]: at SerialPort.emit (node:events:514:28) Feb 18 22:12:02 volumiobookworm volumio[5625]: at settings.binding.open.then.opening (/data/plugins/system_hardware/serialampcontroller/node_modules/@serialport/stream/dist/index.js:111:18) Feb 18 22:12:02 volumiobookworm volumio[5625]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 22:12:02 volumiobookworm sudo[5992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-02-18 22:11' Feb 18 22:12:02 volumiobookworm sudo[5992]: 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="6a71b1b8f832c66e3bde56982bbc4fb0dcc139a1" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="572bccfe6facd2cbc0b66d419c4ed66a3b0126c1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 19 20:30:59 UTC 2024" VOLUMIO_VERSION="0.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="68e75b944cee4d40104b64931fbc3e36"