Feb 25 22:35:00 pi5 ntpd[1183]: CLOCK: time stepped by 11041.140526 Feb 25 22:35:00 pi5 ntpd[1183]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: A device disappeared from network Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: A device disappeared from network Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: A device disappeared from network Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: adding 55adf381-82ae-4c8d-943d-1546a65e0601 Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: Found device Volumio Feb 25 22:35:00 pi5 volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 25 22:35:00 pi5 volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: this is already registered, 55adf381-82ae-4c8d-943d-1546a65e0601 Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: Found device Volumio Feb 25 22:35:00 pi5 volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 25 22:35:00 pi5 volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: this is already registered, 55adf381-82ae-4c8d-943d-1546a65e0601 Feb 25 22:35:00 pi5 volumio[1354]: info: Discovery: Found device Volumio Feb 25 22:35:00 pi5 volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 25 22:35:00 pi5 volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 25 22:35:00 pi5 volumio[1354]: info: MyVolumio login type: Token Feb 25 22:35:00 pi5 volumio[1354]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 22:35:01 pi5 volumio[1354]: info: MyVolumio token set successfully Feb 25 22:35:01 pi5 volumio[1354]: info: MYVOLUMIO: Adding device Feb 25 22:35:01 pi5 volumio[1354]: info: MYVOLUMIO: Evaluating Server Feb 25 22:35:01 pi5 volumio[1354]: info: MyVolumio Plan changed: premium Feb 25 22:35:01 pi5 volumio[1354]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 25 22:35:01 pi5 volumio[1354]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 22:35:01 pi5 volumio[1354]: info: Removing audio output: Feb 25 22:35:01 pi5 volumio[1354]: info: MYVOLUMIO: Adding device Feb 25 22:35:01 pi5 volumio[1354]: info: MYVOLUMIO: Evaluating Server Feb 25 22:35:01 pi5 volumio[1354]: info: Remote config written successfully Feb 25 22:35:01 pi5 volumio[1354]: info: Starting Tunnel 1 Feb 25 22:35:01 pi5 volumio[1354]: info: Starting Tunnel Connection Checker Feb 25 22:35:01 pi5 volumio[1354]: info: Completed starting MyVolumio Plugin Feb 25 22:35:01 pi5 volumio[1354]: info: MYVolumio Device enabled Feb 25 22:35:01 pi5 volumio[1354]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 25 22:35:01 pi5 volumio[1354]: info: MyVolumio status changed Feb 25 22:35:01 pi5 volumio[1354]: info: Streaming services startup Feb 25 22:35:01 pi5 volumio[1354]: info: Starting Streaming Daemon Feb 25 22:35:01 pi5 sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 22:35:01 pi5 sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:01 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 25 22:35:01 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 25 22:35:01 pi5 sudo[1836]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:01 pi5 volumio[1354]: info: [1740522901627] Starting BluetoothController Feb 25 22:35:01 pi5 upmpdcli[1840]: writing RSA key Feb 25 22:35:01 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 25 22:35:02 pi5 sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 25 22:35:02 pi5 sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1846]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: MultiRoom plugin initialized Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: STOPPING SNAPCLIENT Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Snap server stop Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: STOPPING volumioStreaming Feb 25 22:35:02 pi5 sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 25 22:35:02 pi5 sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 25 22:35:02 pi5 sudo[1865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 25 22:35:02 pi5 sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 25 22:35:02 pi5 sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1871]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 25 22:35:02 pi5 sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 25 22:35:02 pi5 sudo[1863]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 sudo[1865]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 25 22:35:02 pi5 sudo[1868]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 25 22:35:02 pi5 volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 25 22:35:02 pi5 volumio[1354]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 25 22:35:02 pi5 volumio[1354]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 25 22:35:02 pi5 volumio[1354]: info: Reading ALSA contributions from plugins. Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Removed streaming files Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: volumioStreaming STOPPED Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: SNAPSERVER STOPPED Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: SNAPCLIENT STOPPED Feb 25 22:35:02 pi5 volumio[1354]: error: Cannot start Volumio Streaming Daemon Feb 25 22:35:02 pi5 volumio[1354]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 22:35:02 pi5 volumio[1354]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 22:35:02 pi5 volumio[1354]: info: Setting Geolocation for MyVolumio to eu3 Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: Asound.conf file written Feb 25 22:35:02 pi5 sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 25 22:35:02 pi5 sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1879]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 25 22:35:02 pi5 volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 25 22:35:02 pi5 volumio[1354]: info: Output device has changed, restarting MPD Feb 25 22:35:02 pi5 volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 sudo[1885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 22:35:02 pi5 sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1885]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 22:35:02 pi5 sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Pushing multiroomSync output for this device Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Pushing multiroomSync output Feb 25 22:35:02 pi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 sudo[1897]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/hciconfig hci0 name Volumio Feb 25 22:35:02 pi5 sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 sudo[1897]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 25 22:35:02 pi5 volumio[1354]: info: Adding METAVOLUMIO REST API Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 25 22:35:02 pi5 volumio[1354]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 25 22:35:02 pi5 volumio[1354]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 25 22:35:02 pi5 volumio[1354]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 25 22:35:02 pi5 volumio[1354]: info: Preparing CD Folders Feb 25 22:35:02 pi5 volumio[1354]: info: Adding CD REST API Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 25 22:35:02 pi5 volumio[1354]: info: Starting UDEV Watcher for CD Feb 25 22:35:02 pi5 volumio[1354]: info: Detecting CD presence with UDEV Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 25 22:35:02 pi5 volumio[1354]: info: Adding inputs REST Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 25 22:35:02 pi5 volumio[1354]: info: Scanning Audio Inputs Feb 25 22:35:02 pi5 volumio[1354]: info: Checking against Known Cards name Feb 25 22:35:02 pi5 volumio[1354]: info: Adding Server instance for streaming Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 25 22:35:02 pi5 volumio[1354]: error: Hi Res Audio Failed Login: Missing Login Data Feb 25 22:35:02 pi5 volumio[1354]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 25 22:35:02 pi5 volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 25 22:35:02 pi5 volumio[1354]: info: Stopping AccessToken refresher cron Feb 25 22:35:02 pi5 sudo[1906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 25 22:35:02 pi5 sudo[1906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 volumio[1354]: info: AccessToken refresher cron started Feb 25 22:35:02 pi5 volumio[1354]: info: Stopping AccessToken refresher cron for QOBUZ Feb 25 22:35:02 pi5 volumio[1354]: info: AccessToken refresher cron started for QOBUZ Feb 25 22:35:02 pi5 volumio[1354]: info: Adding TIDAL REST API Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: Adding QOBUZ REST API Endpoints Feb 25 22:35:02 pi5 volumio[1354]: info: Bluetooth name changed to Volumio Feb 25 22:35:02 pi5 volumio[1354]: info: MPD Permissions set Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 sudo[1906]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: Starting Shairport Sync Feb 25 22:35:02 pi5 sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 22:35:02 pi5 sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:02 pi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 22:35:02 pi5 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 22:35:02 pi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 22:35:02 pi5 systemd[1]: shairport-sync.service: Consumed 1.528s CPU time. Feb 25 22:35:02 pi5 systemd[1]: mpd.service: Deactivated successfully. Feb 25 22:35:02 pi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 22:35:02 pi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 25 22:35:02 pi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 22:35:02 pi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Found cast device: Chromecast-Ultra-b8d5e9e179c52d9e75eab34ae52a3903 Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Found cast device: Chromecast-Ultra-bb5475a65116e0c6bfcdcd498eee7139 Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Found cast device: Chromecast-5702c0fa26409e4dc282cbccce3735c4 Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 volumio[1354]: info: MRS: Found cast device: Google-Nest-Hub-da739cebc7bb692ba8bab67636d07159 Feb 25 22:35:02 pi5 volumio[1354]: info: Adding audio output: Feb 25 22:35:02 pi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 22:35:02 pi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 22:35:02 pi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 22:35:02 pi5 sudo[1916]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: Shairport-Sync Started Feb 25 22:35:02 pi5 sudo[1923]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 22:35:02 pi5 sudo[1923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 22:35:02 pi5 sudo[1923]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: info: Successfully Added MyVolumio device Feb 25 22:35:02 pi5 volumio[1354]: info: Setting Geolocation for MyVolumio to eu3 Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:02 pi5 mpd[1940]: Feb 25 22:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 22:35:02 pi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 22:35:02 pi5 sudo[1887]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:02 pi5 volumio[1354]: error: updateQueue error: null Feb 25 22:35:02 pi5 volumio[1354]: info: Successfully Added MyVolumio device Feb 25 22:35:03 pi5 volumio[1354]: info: Updating MyVolumio device info Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: Updating MyVolumio device info Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 22:35:03 pi5 volumio[1354]: info: Successfully Updated MyVolumio device Feb 25 22:35:03 pi5 volumio[1354]: info: Successfully Updated MyVolumio device Feb 25 22:35:05 pi5 sudo[1945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 25 22:35:05 pi5 sudo[1945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:05 pi5 volumio[1354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 22:35:05 pi5 volumio[1354]: Error: Missing or invalid serial Feb 25 22:35:05 pi5 volumio[1354]: at Object.marshallMessage [as marshall] (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/message.js:80:30) Feb 25 22:35:05 pi5 volumio[1354]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:122:28) Feb 25 22:35:05 pi5 volumio[1354]: at Object.onceWrapper (node:events:628:28) Feb 25 22:35:05 pi5 volumio[1354]: at EventEmitter.emit (node:events:514:28) Feb 25 22:35:05 pi5 volumio[1354]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:102:10 Feb 25 22:35:05 pi5 volumio[1354]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:83:16 Feb 25 22:35:05 pi5 volumio[1354]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Feb 25 22:35:05 pi5 volumio[1354]: at Socket.emit (node:events:514:28) Feb 25 22:35:05 pi5 volumio[1354]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 25 22:35:05 pi5 volumio[1354]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Feb 25 22:35:05 pi5 volumio[1354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 22:35:05 pi5 systemd[1]: /lib/systemd/system/volumiobt.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 22:35:05 pi5 systemd[1]: /lib/systemd/system/volumiobt.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 22:35:05 pi5 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 25 22:35:05 pi5 sudo[1945]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:05 pi5 sudo[1959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 25 22:35:05 pi5 sudo[1959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 22:35:05 pi5 volumiobt[1960]: /usr/bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 25 22:35:05 pi5 sudo[1959]: pam_unix(sudo:session): session closed for user root Feb 25 22:35:05 pi5 sudo[1965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-02-25 22:34' Feb 25 22:35:05 pi5 sudo[1965]: 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="7b98f942de004453e905402108c858bae0cff12c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Feb 25 17:26:39 UTC 2025" VOLUMIO_VERSION="0.037" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d9953f9e3bd47cc8fff2b863ad970551"