-- Logs begin at Thu 2019-02-14 06:11:58 AST, end at Mon 2026-01-26 18:43:50 AST. -- Jan 22 20:03:08 klipsch-volumio sudo[7024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding audio output: Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding METAVOLUMIO REST API Endpoints Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Preparing CD Folders Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding CD REST API Endpoints Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Starting UDEV Watcher for CD Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Detecting CD presence with UDEV Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 22 20:03:08 klipsch-volumio sudo[7024]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:39 klipsch-volumio systemd[1]: Starting Music Player Daemon... Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02d47980 does not match aorg 0000000000.00000000 from server@170.187.142.180 xmt 0xed226c9b.dcba0d0f Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding inputs REST Endpoints Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Scanning Audio Inputs Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02d35794 does not match aorg 0000000000.00000000 from server@172.234.44.141 xmt 0xed226c9b.dc4e83d1 Jan 26 18:43:39 klipsch-volumio systemd[1]: Starting Daily apt download activities... Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02cafdc5 does not match aorg 0000000000.00000000 from server@96.126.122.39 xmt 0xed226c9b.dc0ddaee Jan 26 18:43:39 klipsch-volumio systemd[1]: Starting Daily man-db regeneration... Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02d21220 does not match aorg 0000000000.00000000 from server@23.157.160.168 xmt 0xed226c9b.dc8af73d Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02d692de does not match aorg 0000000000.00000000 from server@173.249.203.227 xmt 0xed226c9b.dece91f8 Jan 26 18:43:39 klipsch-volumio ntpd[768]: receive: Unexpected origin timestamp 0xed1d393c.02d598b4 does not match aorg 0000000000.00000000 from server@216.197.228.230 xmt 0xed226c9b.e35fae97 Jan 26 18:43:39 klipsch-volumio sudo[7021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 18:43:39 klipsch-volumio sudo[7021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:39 klipsch-volumio sudo[7021]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Checking against Known Cards name Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: Adding Server instance for streaming Jan 26 18:43:39 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 26 18:43:40 klipsch-volumio volumio[1013]: error: Hi Res Audio Failed Login: Missing Login Data Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Refreshing QOBUZ token Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 26 18:43:40 klipsch-volumio sudo[7048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 18:43:40 klipsch-volumio sudo[7048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 26 18:43:40 klipsch-volumio sudo[7057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 18:43:40 klipsch-volumio sudo[7057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:40 klipsch-volumio sudo[7048]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding TIDAL REST API Endpoints Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 26 18:43:40 klipsch-volumio systemd[1]: man-db.service: Succeeded. Jan 26 18:43:40 klipsch-volumio systemd[1]: Started Daily man-db regeneration. Jan 26 18:43:40 klipsch-volumio sudo[7057]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:40 klipsch-volumio volumio[1013]: error: Upnp client error: Error: This socket has been ended by the other party Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:40 klipsch-volumio sudo[7062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 18:43:40 klipsch-volumio sudo[7062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Bluetooth name changed to Klipsch Volumio Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: MPD Permissions set Jan 26 18:43:40 klipsch-volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 18:43:40 klipsch-volumio sudo[7062]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Discovery: A device disappeared from network Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Updating MyVolumio device info Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Discovery: A device disappeared from network Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Updating MyVolumio device info Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Successfully Added MyVolumio device Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Executing endpoint qc_getconfig Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 18:43:40 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:40.756 [7070.7070] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 18:43:40 klipsch-volumio mpd[7042]: Jan 26 18:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 18:43:40 klipsch-volumio volumio[1013]: info: Starting Shairport Sync Jan 26 18:43:40 klipsch-volumio sudo[7095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 18:43:40 klipsch-volumio sudo[7095]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:40 klipsch-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 18:43:40 klipsch-volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 18:43:40 klipsch-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Discovery: adding 2950bc88-eaf4-4ca8-934c-446075507c9f Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Discovery: Found device Klipsch Volumio Jan 26 18:43:41 klipsch-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:41 klipsch-volumio sudo[7015]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:41 klipsch-volumio systemd[1]: Started Music Player Daemon. Jan 26 18:43:41 klipsch-volumio sudo[7095]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Successfully Added MyVolumio device Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Discovery: this is already registered, 2950bc88-eaf4-4ca8-934c-446075507c9f Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Discovery: Found device Klipsch Volumio Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Access Token successfully retrieved Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: [1769467421091] CoreMusicLibrary::Adding element QOBUZ Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:43:41 klipsch-volumio volumio[1013]: Cannot find translation for source YouTube Music Jan 26 18:43:41 klipsch-volumio volumio[1013]: Cannot find translation for source 80s80s Radio Jan 26 18:43:41 klipsch-volumio volumio[1013]: Cannot find translation for source Radio Paradise Jan 26 18:43:41 klipsch-volumio volumio[1013]: Cannot find translation for source Randomizer Jan 26 18:43:41 klipsch-volumio volumio[1013]: Cannot find translation for source QOBUZ Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Stopping AccessToken refresher cron for QOBUZ Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.118 [7070.7070] INFO VolumeManager: [0x1725360]: Setting new playback volume: 75 Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.119 [7070.7070] INFO VolumeManager: [0x1725360]: Setting new mute state: 0 Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.119 [7070.7070] INFO QobuzConnect: [0x1725d30]: Client initialized! Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.119 [7070.7070] INFO SampleApp: Starting Avahi advertising, name: Klipsch Volumio, service name: _qobuz-connect._tcp Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.128 [7070.7070] INFO LocalConfigManager: [0x1724c40]: Starting Local Configuration server Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.128 [7070.7070] INFO SampleApp: Starting Local configuration server Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.128 [7070.7070] INFO SampleApp: Playback volume changed: 75 Jan 26 18:43:41 klipsch-volumio qobuz-connect[7070]: 20260126 18:43:41.129 [7070.7070] INFO SampleApp: Connected to UNIX socket client 0x171af60 Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: AccessToken refresher cron started for QOBUZ Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Adding QOBUZ REST API Endpoints Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Shairport-Sync Started Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:41 klipsch-volumio volumio[1013]: error: updateQueue error: null Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Successfully Updated MyVolumio device Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Successfully Updated MyVolumio device Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:41 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:42 klipsch-volumio volumiologrotate[602]: ls: cannot access '/var/log/samba/log.wb-KLIPSCH': No such file or directory Jan 26 18:43:42 klipsch-volumio volumiologrotate[602]: ls: cannot access 'VOLUMIO': No such file or directory Jan 26 18:43:42 klipsch-volumio volumio-time-update[598]: volumio-time-update-util: Date not found in response Jan 26 18:43:42 klipsch-volumio volumio-time-update[598]: volumio-time-update-util: Retrying in 5 seconds... Jan 26 18:43:42 klipsch-volumio sudo[7158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 26 18:43:42 klipsch-volumio sudo[7158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:42 klipsch-volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 26 18:43:42 klipsch-volumio sudo[7158]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: Remote SSH Started Jan 26 18:43:42 klipsch-volumio autossh[7161]: port set to 0, monitoring disabled Jan 26 18:43:42 klipsch-volumio autossh[7161]: starting ssh (count 1) Jan 26 18:43:42 klipsch-volumio autossh[7161]: ssh child pid is 7164 Jan 26 18:43:42 klipsch-volumio volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 80 Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:42 klipsch-volumio sudo[7184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 26 18:43:42 klipsch-volumio sudo[7184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:42 klipsch-volumio systemd[1]: Started Volumio Bluetooth Module. Jan 26 18:43:42 klipsch-volumio sudo[7184]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:42 klipsch-volumio volumio[1013]: info: Volumio BT Module successfully started Jan 26 18:43:42 klipsch-volumio sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 26 18:43:42 klipsch-volumio sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:42 klipsch-volumio volumiobt[7188]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 26 18:43:42 klipsch-volumio sudo[7195]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:43 klipsch-volumio systemd[1]: apt-daily.service: Succeeded. Jan 26 18:43:43 klipsch-volumio systemd[1]: Started Daily apt download activities. Jan 26 18:43:43 klipsch-volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jan 26 18:43:43 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:43 klipsch-volumio volumiossh-tunnel[7160]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Received Get System Info Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Discovery: Getting this device information Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: TidalConnect service stoped! Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 18:43:43 klipsch-volumio sudo[7232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 18:43:43 klipsch-volumio sudo[7232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:43 klipsch-volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 26 18:43:43 klipsch-volumio sudo[7232]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Executing endpoint tc_getconfig Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 26 18:43:43 klipsch-volumio vtcs[7234]: STARTING TidalConnect services, version: 1.5.2.56 Jan 26 18:43:43 klipsch-volumio vtcs[7234]: STARTED TidalConnect services. Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Executing endpoint tc_connect Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: Connecting to TidalConnect Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreStateMachine::pushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: error: Could not process JSON: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreStateMachine::pushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:43 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:43 klipsch-volumio volumio[1013]: error: Could not process JSON: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Jan 26 18:43:43 klipsch-volumio sudo[7263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 26 18:43:43 klipsch-volumio sudo[7263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:43 klipsch-volumio volumiobt[7188]: pulseaudio: no process found Jan 26 18:43:43 klipsch-volumio sudo[7263]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:44 klipsch-volumio dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.28' (uid=1000 pid=7273 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:44 klipsch-volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 26 18:43:44 klipsch-volumio sudo[7282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 18:43:44 klipsch-volumio sudo[7282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:44 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 18:43:44 klipsch-volumio dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Successfully called chroot. Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Successfully dropped privileges. Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Successfully limited resources. Jan 26 18:43:44 klipsch-volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Running. Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Canary thread running. Jan 26 18:43:44 klipsch-volumio rtkit-daemon[7276]: Watchdog thread running. Jan 26 18:43:44 klipsch-volumio dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.29' (uid=0 pid=7276 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 26 18:43:44 klipsch-volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:44 klipsch-volumio systemd[1]: Starting Authorization Manager... Jan 26 18:43:44 klipsch-volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 18:43:44 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 18:43:44 klipsch-volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 18:43:44 klipsch-volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 18:43:44 klipsch-volumio systemd[1]: Started MPD Monitor Service. Jan 26 18:43:44 klipsch-volumio mpd_monitor.sh[7291]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 18:43:44 klipsch-volumio sudo[7282]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:44 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: Successfully started MPD Monitor Jan 26 18:43:44 klipsch-volumio polkitd[7290]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 26 18:43:44 klipsch-volumio dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 26 18:43:44 klipsch-volumio systemd[1]: Started Authorization Manager. Jan 26 18:43:44 klipsch-volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 26 18:43:44 klipsch-volumio systemd[1]: Started Daily apt upgrade and clean activities. Jan 26 18:43:44 klipsch-volumio pulseaudio[7273]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 26 18:43:44 klipsch-volumio pulseaudio[7273]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Applying permissions Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Setting BT discoverable and pairable Jan 26 18:43:44 klipsch-volumio bluetoothd[735]: Endpoint registered: sender=:1.49 path=/MediaEndpoint/A2DPSource Jan 26 18:43:44 klipsch-volumio bluetoothd[735]: Endpoint registered: sender=:1.49 path=/MediaEndpoint/A2DPSink Jan 26 18:43:44 klipsch-volumio pulseaudio[7273]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 26 18:43:44 klipsch-volumio kernel: Bluetooth: RFCOMM TTY layer initialized Jan 26 18:43:44 klipsch-volumio kernel: Bluetooth: RFCOMM socket layer initialized Jan 26 18:43:44 klipsch-volumio kernel: Bluetooth: RFCOMM ver 1.11 Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [96B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [77B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [77B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [33B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [65B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [78B blob data] Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> -e power on Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Invalid command in menu main: -e Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Use "help" for a list of available commands in a menu. Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Use "menu " if you want to enter any submenu. Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Use "back" if you want to return to menu main. Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> agent on Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Agent is already registered Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> discoverable on Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> pairable on Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> agent NoInputNoOutput Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: Agent is already registered Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> default-agent Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [bluetoothctl]> quit Jan 26 18:43:44 klipsch-volumio bluetoothd[735]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/E4:5F:01:6C:E7:54/settings: (Failed to create file “/usr/var/lib/bluetooth/E4:5F:01:6C:E7:54/settings.RJZGJ3”: No such file or directory) Jan 26 18:43:44 klipsch-volumio volumiobt[7188]: [65B blob data] Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: MRS: Getting audio outputs on start Jan 26 18:43:44 klipsch-volumio volumio[1013]: info: MRS: Requesting all other devices output Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:45 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:46 klipsch-volumio volumio[1013]: info: TidalConnect service started! Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:47 klipsch-volumio volumio-time-update[598]: volumio-time-update-util: Fetching time from Volumio... Jan 26 18:43:47 klipsch-volumio volumio-time-update[598]: volumio-time-update-util: Setting system time to: 2026-01-26 18:43:47 Jan 26 18:43:47 klipsch-volumio sudo[7337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-01-26 18:43:47 Jan 26 18:43:47 klipsch-volumio sudo[7337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:47 klipsch-volumio dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.52' (uid=0 pid=7338 comm="timedatectl set-time 2026-01-26 18:43:47 ") Jan 26 18:43:48 klipsch-volumio systemd[1]: Starting Time & Date Service... Jan 26 18:43:48 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 18:43:48 klipsch-volumio dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 26 18:43:48 klipsch-volumio systemd[1]: Started Time & Date Service. Jan 26 18:43:47 klipsch-volumio systemd-timedated[7341]: Changed local time to Mon Jan 26 18:43:47 2026 Jan 26 18:43:47 klipsch-volumio sudo[7337]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:47 klipsch-volumio volumio-time-update[598]: volumio-time-update-util: System time updated successfully. Jan 26 18:43:47 klipsch-volumio systemd[1]: Started Volumio Time Update Utility. Jan 26 18:43:47 klipsch-volumio systemd[1]: Reached target Multi-User System. Jan 26 18:43:47 klipsch-volumio systemd[1]: Reached target Graphical Interface. Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 18:43:47 klipsch-volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 18:43:47 klipsch-volumio volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 18:43:47 klipsch-volumio volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 80 Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:47 klipsch-volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 18:43:47 klipsch-volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:47 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:48 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:48 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:48 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:48 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:48 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::getQueue Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CorePlayQueue::getQueue Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioNext Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::next Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::stop Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::stPlaybackTimer Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::updateTrackBlock Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrackBlock Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreStateMachine::pushState Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:49 klipsch-volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:49 klipsch-volumio volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:43:49 klipsch-volumio volumio[1013]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Jan 26 18:43:49 klipsch-volumio volumio[1013]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at /volumio/app/index.js:326:81 Jan 26 18:43:49 klipsch-volumio volumio[1013]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at CoreStateMachine.next (/volumio/app/statemachine.js:1180:12) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at Socket.emit (events.js:315:20) Jan 26 18:43:49 klipsch-volumio volumio[1013]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 26 18:43:49 klipsch-volumio volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jan 26 18:43:49 klipsch-volumio volumio[1013]: code: 'NumberExpected' Jan 26 18:43:49 klipsch-volumio volumio[1013]: } Jan 26 18:43:49 klipsch-volumio volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:43:50 klipsch-volumio sudo[7356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 18:42 Jan 26 18:43:50 klipsch-volumio sudo[7356]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"