-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-02-11 17:22:18 CET. -- Feb 11 17:21:00 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 17:21:00 motivo volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 11 17:21:00 motivo volumio[1107]: info: CoreCommandRouter::volumioGetState Feb 11 17:21:00 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:00 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 11 17:21:01 motivo volumio[1107]: info: Executing endpoint metavolumio Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 17:21:01 motivo volumio[1107]: info: Executing endpoint metavolumio Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 17:21:01 motivo volumio[1107]: info: Executing endpoint metavolumio Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 17:21:01 motivo volumio[1107]: info: Received Get System Info Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:21:01 motivo volumio[1107]: info: Discovery: Getting this device information Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::volumioGetState Feb 11 17:21:01 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:01 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:21:02 motivo ntpd[798]: Soliciting pool server 178.63.52.50 Feb 11 17:21:03 motivo ntpd[798]: Soliciting pool server 157.90.24.29 Feb 11 17:21:03 motivo volumio[1107]: info: BOOT COMPLETED Feb 11 17:21:04 motivo ntpd[798]: Soliciting pool server 129.250.35.251 Feb 11 17:21:04 motivo ntpd[798]: Soliciting pool server 148.251.235.164 Feb 11 17:21:04 motivo ntpd[798]: Soliciting pool server 130.61.89.107 Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: Not Reporting Auto name since its the default one Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 11 17:21:04 motivo volumio[1107]: No protocol specified Feb 11 17:21:04 motivo volumio[1107]: xcb_connection_has_error() returned true Feb 11 17:21:04 motivo sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 11 17:21:04 motivo sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:21:04 motivo sudo[1885]: pam_unix(sudo:session): session closed for user root Feb 11 17:21:04 motivo sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 11 17:21:04 motivo sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 17:21:04 motivo systemd[1]: Started RAAT DAEMON. Feb 11 17:21:04 motivo sudo[1887]: pam_unix(sudo:session): session closed for user root Feb 11 17:21:04 motivo volumio[1107]: info: Raat Daemon started successfully Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 17:21:04 motivo volumio[1107]: info: Executing endpoint restartRAATSocket Feb 11 17:21:04 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 11 17:21:04 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:04 motivo volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 11 17:21:05 motivo ntpd[798]: Soliciting pool server 128.140.37.196 Feb 11 17:21:05 motivo ntpd[798]: Soliciting pool server 129.250.35.250 Feb 11 17:21:05 motivo ntpd[798]: Soliciting pool server 131.188.3.221 Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:21:05 motivo volumio-remote-updater[719]: No test mode Feb 11 17:21:05 motivo volumio-remote-updater[719]: No alpha test mode Feb 11 17:21:05 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 17:21:06 motivo ntpd[798]: Soliciting pool server 139.162.187.236 Feb 11 17:21:06 motivo volumio[1107]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 17:21:06 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 17:21:06 motivo ntpd[798]: Soliciting pool server 188.68.34.173 Feb 11 17:21:07 motivo ntpd[798]: Soliciting pool server 178.254.28.54 Feb 11 17:21:07 motivo ntpd[798]: Soliciting pool server 94.16.122.152 Feb 11 17:21:07 motivo volumio[1107]: info: RAAT: Requesting Headphone Status Feb 11 17:21:07 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 11 17:21:07 motivo volumio[1107]: info: MCU Signalled Headphone Mode Disabled Feb 11 17:21:07 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 11 17:21:07 motivo volumio[1107]: info: Reporting Headphone State: false Feb 11 17:21:07 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:21:07 motivo volumio[1107]: info: Updating RAAT Signal Path Feb 11 17:21:08 motivo ntpd[798]: Soliciting pool server 93.177.65.20 Feb 11 17:21:08 motivo ntpd[798]: Soliciting pool server 2a02:8106:21:9400::5 Feb 11 17:21:08 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 17:21:09 motivo ntpd[798]: Soliciting pool server 46.21.2.169 Feb 11 17:21:10 motivo ntpd[798]: receive: Unexpected origin timestamp 0xed372af6.0038b31e does not match aorg 0000000000.00000000 from server@46.21.2.169 xmt 0xed372af6.6a2fd044 Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 17:21:16 motivo volumio[1107]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 17:21:16 motivo volumio[1107]: info: Received Get System Version Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:21:16 motivo volumio[1107]: info: Received Get System Info Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 17:21:16 motivo volumio[1107]: info: Discovery: Getting this device information Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::volumioGetState Feb 11 17:21:16 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:16 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 17:21:51 motivo volumio[1107]: info: CoreCommandRouter::volumioPlay Feb 11 17:21:51 motivo volumio[1107]: info: CoreStateMachine::play index undefined Feb 11 17:21:51 motivo volumio[1107]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:21:51 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:51 motivo volumio[1107]: info: CoreStateMachine::startPlaybackTimer Feb 11 17:21:51 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:51 motivo volumio[1107]: info: [1770826911016] ControllerTidal::clearAddPlayTrack Feb 11 17:21:52 motivo volumio[1107]: info: CoreCommandRouter::volumioPlay Feb 11 17:21:52 motivo volumio[1107]: info: CoreStateMachine::play index undefined Feb 11 17:21:52 motivo volumio[1107]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:21:52 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:52 motivo volumio[1107]: info: CoreStateMachine::startPlaybackTimer Feb 11 17:21:52 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:52 motivo volumio[1107]: info: [1770826912395] ControllerTidal::clearAddPlayTrack Feb 11 17:21:57 motivo volumio[1107]: info: CoreCommandRouter::volumioPlay Feb 11 17:21:57 motivo volumio[1107]: info: CoreStateMachine::play index undefined Feb 11 17:21:57 motivo volumio[1107]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 17:21:57 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:57 motivo volumio[1107]: info: CoreStateMachine::startPlaybackTimer Feb 11 17:21:57 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:21:57 motivo volumio[1107]: info: [1770826917256] ControllerTidal::clearAddPlayTrack Feb 11 17:22:00 motivo volumio[1107]: info: CoreCommandRouter::volumioGetQueue Feb 11 17:22:00 motivo volumio[1107]: info: CoreStateMachine::getQueue Feb 11 17:22:00 motivo volumio[1107]: info: CorePlayQueue::getQueue Feb 11 17:22:03 motivo volumio[1107]: info: Preload queue cleared Feb 11 17:22:08 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 11 17:22:08 motivo volumio[1107]: info: handleBrowseUri took 128 milliseconds Feb 11 17:22:08 motivo volumio[1107]: info: Preload queue cleared Feb 11 17:22:08 motivo volumio[1107]: info: Preload queue cleared Feb 11 17:22:17 motivo volumio[1107]: Searching plugin music_service/qobuz Feb 11 17:22:17 motivo volumio[1107]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 11 17:22:18 motivo volumio[1107]: info: CorePlayQueue::getTrack 0 Feb 11 17:22:18 motivo volumio[1107]: info: CorePlayQueue::getTrack 1 Feb 11 17:22:18 motivo volumio[1107]: info: Prefetching next song Feb 11 17:22:18 motivo volumio[1107]: info: [1770826938294] ControllerTidal::prefetch Feb 11 17:22:18 motivo volumio[1107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:22:18 motivo volumio[1107]: TypeError: Cannot read property 'highestSoundQuality' of undefined Feb 11 17:22:18 motivo volumio[1107]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19583) Feb 11 17:22:18 motivo volumio[1107]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:20287) Feb 11 17:22:18 motivo volumio[1107]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Feb 11 17:22:18 motivo volumio[1107]: at listOnTimeout (internal/timers.js:554:17) Feb 11 17:22:18 motivo volumio[1107]: at processTimers (internal/timers.js:497:7) Feb 11 17:22:18 motivo volumio[1107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:22:18 motivo sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 17:21 Feb 11 17:22:18 motivo sudo[2052]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 03:22:29 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="7f33e77759202d530afe461231bb6a6d"