Feb 25 18:34:37 pi5 ntpd[1193]: CLOCK: time stepped by 4053.704335 Feb 25 18:34:37 pi5 ntpd[1193]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 25 18:34:37 pi5 volumio[1364]: info: MRS: Found cast device: Chromecast-Ultra-b8d5e9e179c52d9e75eab34ae52a3903 Feb 25 18:34:37 pi5 volumio[1364]: info: MRS: Found cast device: Chromecast-Ultra-bb5475a65116e0c6bfcdcd498eee7139 Feb 25 18:34:37 pi5 volumio[1364]: info: MRS: Found cast device: Chromecast-5702c0fa26409e4dc282cbccce3735c4 Feb 25 18:34:37 pi5 volumio[1364]: info: MRS: Found cast device: Google-Nest-Hub-da739cebc7bb692ba8bab67636d07159 Feb 25 18:34:37 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:34:38 pi5 volumio[1364]: info: TidalConnect service stoped! Feb 25 18:34:38 pi5 volumio[1364]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 25 18:34:38 pi5 volumio[1364]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 25 18:34:38 pi5 sudo[1993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 25 18:34:38 pi5 sudo[1993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:34:38 pi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 25 18:34:38 pi5 sudo[1993]: pam_unix(sudo:session): session closed for user root Feb 25 18:34:38 pi5 volumio[1364]: info: Executing endpoint tc_getconfig Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 25 18:34:38 pi5 vtcs[1996]: STARTING TidalConnect services, version: 1.5.0.45 Feb 25 18:34:38 pi5 vtcs[1996]: STARTED TidalConnect services. Feb 25 18:34:38 pi5 volumio[1364]: info: Executing endpoint tc_connect Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 25 18:34:38 pi5 volumio[1364]: info: Connecting to TidalConnect Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::servicePushState Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 25 18:34:38 pi5 volumio[1364]: verbose: CURRENT POSITION 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::syncState stateService stop Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: No code Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::servicePushState Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 25 18:34:38 pi5 volumio[1364]: verbose: CURRENT POSITION 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::syncState stateService stop Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: No code Feb 25 18:34:38 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:38 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:38 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:38 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:34:39 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 18:34:39 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:34:39 pi5 sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 25 18:34:39 pi5 sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:34:39 pi5 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 25 18:34:39 pi5 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 25 18:34:39 pi5 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 25 18:34:39 pi5 sudo[2018]: pam_unix(sudo:session): session closed for user root Feb 25 18:34:39 pi5 volumio[1364]: info: Remote SSH Started Feb 25 18:34:39 pi5 autossh[2021]: port set to 0, monitoring disabled Feb 25 18:34:39 pi5 autossh[2021]: starting ssh (count 1) Feb 25 18:34:39 pi5 autossh[2021]: ssh child pid is 2024 Feb 25 18:34:39 pi5 volumio[1364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Feb 25 18:34:39 pi5 volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 25 18:34:39 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:39 pi5 volumiossh-tunnel[2024]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 25 18:34:39 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:34:40 pi5 volumio[1364]: info: MRS: Getting audio outputs on start Feb 25 18:34:40 pi5 volumio[1364]: info: MRS: Requesting all other devices output Feb 25 18:34:41 pi5 volumio[1364]: info: TidalConnect service started! Feb 25 18:34:41 pi5 volumio[1364]: [Metrics] CommandRouter: 19s 952.35ms Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:34:41 pi5 volumio[1364]: info: VolumeController:: Setting startup Volume 35 Feb 25 18:34:41 pi5 volumio[1364]: info: VolumeController::SetAlsaVolume35 Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::Close All Modals sent Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::Close All Modals sent Feb 25 18:34:41 pi5 volumio[1364]: info: CoreStateMachine::pushState Feb 25 18:34:41 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 18:34:41 pi5 volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 25 18:34:41 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 25 18:34:41 pi5 volumio[1364]: info: MRS: Pushing multiroomSync output Feb 25 18:34:42 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 18:34:42 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 18:34:42 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 18:34:43 pi5 upmpdcli[2047]: writing RSA key Feb 25 18:34:43 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 18:34:43 pi5 systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 25 18:34:45 pi5 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 25 18:34:47 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 18:34:48 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 18:34:48 pi5 volumio[1364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Feb 25 18:34:48 pi5 volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 25 18:34:48 pi5 volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 25 18:34:48 pi5 volumio[1364]: info: BOOT COMPLETED Feb 25 18:34:51 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 18:34:53 pi5 volumio-remote-updater[1014]: No test mode Feb 25 18:34:53 pi5 volumio-remote-updater[1014]: No alpha test mode Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 25 18:34:53 pi5 volumio[1364]: info: Enabling MyMusic plugin bluetooth Feb 25 18:34:53 pi5 volumio[1364]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Feb 25 18:34:53 pi5 volumio[1364]: info: Enabling plugin bluetooth Feb 25 18:34:53 pi5 volumio[1364]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 18:34:53 pi5 volumio[1364]: info: Done. Feb 25 18:34:53 pi5 sudo[2073]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/hciconfig hci0 name Volumio Feb 25 18:34:53 pi5 sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:34:53 pi5 sudo[2073]: pam_unix(sudo:session): session closed for user root Feb 25 18:34:53 pi5 volumio[1364]: info: Bluetooth name changed to Volumio Feb 25 18:34:53 pi5 volumio[1364]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.785","updateavailable":true} Feb 25 18:34:53 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 25 18:34:55 pi5 volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 18:34:56 pi5 sudo[2076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 25 18:34:56 pi5 sudo[2076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:34:56 pi5 volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:34:56 pi5 volumio[1364]: Error: Missing or invalid serial Feb 25 18:34:56 pi5 volumio[1364]: at Object.marshallMessage [as marshall] (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/message.js:80:30) Feb 25 18:34:56 pi5 volumio[1364]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:122:28) Feb 25 18:34:56 pi5 volumio[1364]: at Object.onceWrapper (node:events:628:28) Feb 25 18:34:56 pi5 volumio[1364]: at EventEmitter.emit (node:events:514:28) Feb 25 18:34:56 pi5 volumio[1364]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/index.js:102:10 Feb 25 18:34:56 pi5 volumio[1364]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:83:16 Feb 25 18:34:56 pi5 volumio[1364]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Feb 25 18:34:56 pi5 volumio[1364]: at Socket.emit (node:events:514:28) Feb 25 18:34:56 pi5 volumio[1364]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 25 18:34:56 pi5 volumio[1364]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) Feb 25 18:34:56 pi5 volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:34:56 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 18:34:56 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 18:34:56 pi5 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 25 18:34:56 pi5 sudo[2076]: pam_unix(sudo:session): session closed for user root Feb 25 18:34:56 pi5 sudo[2090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 25 18:34:56 pi5 sudo[2090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:34:56 pi5 volumiobt[2091]: /usr/bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 25 18:34:56 pi5 sudo[2090]: pam_unix(sudo:session): session closed for user root Feb 25 18:34:56 pi5 sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-02-25 18:33' Feb 25 18:34:56 pi5 sudo[2096]: 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"