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
- Improved translations
- Fix for Allo Piano DAC on RPi with kernel 6.6.x
NEW ADDITIONS
- Add support for Raspberry Pi Display V2
- Bump kernel to 6.6.62
","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"