Mar 26 18:45:02 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 18:45:03 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 18:45:04 volumio login[1354]: pam_unix(login:auth): authentication failure; logname=LOGIN uid=0 euid=0 tty=/dev/tty1 ruser= rhost= user=volumio Mar 26 18:45:06 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:08 volumio login[1354]: FAILED LOGIN (2) on '/dev/tty1' FOR 'volumio', Authentication failure Mar 26 18:45:08 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:09 volumio volumio[1338]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 26 18:45:09 volumio volumio[1338]: info: Completed starting MyVolumio Plugin Mar 26 18:45:09 volumio volumio[1338]: [Metrics] CommandRouter: 58s 429.66ms Mar 26 18:45:09 volumio volumio[1338]: info: CoreCommandRouter::volumiosetStartupVolume Mar 26 18:45:09 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 18:45:09 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 18:45:09 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:09 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:10 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 26 18:45:10 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 18:45:10 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 26 18:45:10 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:14 volumio login[1354]: pam_unix(login:session): session opened for user root(uid=0) by LOGIN(uid=0) Mar 26 18:45:14 volumio systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 26 18:45:14 volumio systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 26 18:45:14 volumio systemd-logind[845]: New session 1 of user root. Mar 26 18:45:14 volumio systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 26 18:45:14 volumio systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 26 18:45:14 volumio (systemd)[2000]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 26 18:45:15 volumio systemd[2000]: Queued start job for default target default.target. Mar 26 18:45:15 volumio systemd[2000]: Created slice app.slice - User Application Slice. Mar 26 18:45:15 volumio systemd[2000]: Reached target paths.target - Paths. Mar 26 18:45:15 volumio systemd[2000]: Reached target timers.target - Timers. Mar 26 18:45:15 volumio systemd[2000]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Mar 26 18:45:15 volumio systemd[2000]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 26 18:45:15 volumio systemd[2000]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 26 18:45:15 volumio systemd[2000]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 26 18:45:15 volumio systemd[2000]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 26 18:45:15 volumio systemd[2000]: Reached target sockets.target - Sockets. Mar 26 18:45:15 volumio systemd[2000]: Reached target basic.target - Basic System. Mar 26 18:45:15 volumio systemd[2000]: mpris-proxy.service - Bluetooth mpris proxy was skipped because of an unmet condition check (ConditionUser=!@system). Mar 26 18:45:15 volumio systemd[2000]: Reached target default.target - Main User Target. Mar 26 18:45:15 volumio systemd[1]: Started user@0.service - User Manager for UID 0. Mar 26 18:45:15 volumio systemd[2000]: Startup finished in 666ms. Mar 26 18:45:15 volumio systemd[1]: Started session-1.scope - Session 1 of User root. Mar 26 18:45:15 volumio login[2015]: ROOT LOGIN on '/dev/tty1' Mar 26 18:45:16 volumio volumio[1338]: info: BOOT COMPLETED Mar 26 18:45:18 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 18:45:19 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:27 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:27 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: info: Retrieving Cloud Streaming UI Mar 26 18:45:30 volumio volumio[1338]: info: Getting Tidal Cloud Configuration Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: info: Getting Qobuz Cloud Configuration Mar 26 18:45:30 volumio volumio[1338]: info: Asking plugin for UI Config Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: info: Getting Spotify Cloud Configuration Mar 26 18:45:30 volumio volumio[1338]: info: Asking plugin for UI Config Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: info: Saving Spotify Acccount Mar 26 18:45:30 volumio volumio[1338]: info: Got Tidal Cloud Configuration Mar 26 18:45:30 volumio volumio[1338]: info: Got it Mar 26 18:45:30 volumio volumio[1338]: info: Got it Mar 26 18:45:30 volumio volumio[1338]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 18:45:30 volumio volumio[1338]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 18:45:30 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand rescan Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: info: Mar 26 18:45:32 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand rescan took 26 milliseconds Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: info: Mar 26 18:45:32 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: info: Mar 26 18:45:32 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 6 milliseconds Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 5 milliseconds Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 4 milliseconds Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: info: Command Router : Notfying DB Updatetrue Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: info: Command Router : Notfying DB Updatetrue Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:32 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: No code Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: ------------------------------ 303ms Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 315 milliseconds Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 318 milliseconds Mar 26 18:45:32 volumio volumio[1338]: info: sendMpdCommand status took 319 milliseconds Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: info: Command Router : Notfying DB Updatetrue Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:32 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:32 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: No code Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:32 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: No code Mar 26 18:45:32 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:32 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:32 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:32 volumio volumio[1338]: info: ------------------------------ 459ms Mar 26 18:45:32 volumio volumio[1338]: info: ------------------------------ 502ms Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: info: Mar 26 18:45:34 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: info: Mar 26 18:45:34 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: info: Mar 26 18:45:34 volumio volumio[1338]: ---------------------------- MPD announces state update: update Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::getState Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 3 milliseconds Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 3 milliseconds Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 3 milliseconds Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: info: Command Router : Notfying DB Updatefalse Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: info: Command Router : Notfying DB Updatefalse Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:34 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: No code Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: ------------------------------ 243ms Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 251 milliseconds Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 252 milliseconds Mar 26 18:45:34 volumio volumio[1338]: info: sendMpdCommand status took 251 milliseconds Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: info: Command Router : Notfying DB Updatefalse Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:34 volumio volumio[1338]: verbose: ControllerMpd::parseState Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:34 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: No code Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: ControllerMpd::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::servicePushState Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 18:45:34 volumio volumio[1338]: verbose: CURRENT POSITION 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState stateService stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: No code Mar 26 18:45:34 volumio volumio[1338]: info: CoreStateMachine::pushState Mar 26 18:45:34 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:34 volumio volumio[1338]: info: CoreCommandRouter::volumioPushState Mar 26 18:45:34 volumio volumio[1338]: info: ------------------------------ 392ms Mar 26 18:45:34 volumio volumio[1338]: info: ------------------------------ 443ms Mar 26 18:45:36 volumio systemd[2000]: Reached target bluetooth.target - Bluetooth. Mar 26 18:45:37 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:39 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:39 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:39 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:44 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:44 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 18:45:45 volumio volumio[1338]: info: CoreCommandRouter::Close All Modals sent Mar 26 18:45:47 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:51 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:51 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:52 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:45:54 volumio volumio[1338]: verbose: New Socket.io Connection to 192.168.0.246 from 192.168.0.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 OPR/128.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: Received Get System Info Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:45:54 volumio volumio[1338]: info: Discovery: Getting this device information Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: Received Get System Info Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:45:54 volumio volumio[1338]: info: Discovery: Getting this device information Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 18:45:54 volumio volumio[1338]: info: Received Get System Info Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:45:54 volumio volumio[1338]: info: Discovery: Getting this device information Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: Listing playlists Mar 26 18:45:54 volumio volumio[1338]: info: Received Get System Info Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 18:45:54 volumio volumio[1338]: info: Discovery: Getting this device information Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 18:45:54 volumio volumio[1338]: info: CoreCommandRouter::volumioGetState Mar 26 18:45:54 volumio volumio[1338]: info: CorePlayQueue::getTrack 0 Mar 26 18:45:55 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 18:45:57 volumio volumio[1338]: info: CoreCommandRouter::volumioGetQueue Mar 26 18:45:57 volumio volumio[1338]: info: CoreStateMachine::getQueue Mar 26 18:45:57 volumio volumio[1338]: info: CorePlayQueue::getQueue Mar 26 18:45:58 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:46:00 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:46:02 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 26 18:46:04 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 18:46:06 volumio volumio[1338]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Mar 26 18:46:06 volumio volumio[1338]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 18:46:06 volumio volumio[1338]: TypeError: Cannot read properties of undefined (reading 'then') Mar 26 18:46:06 volumio volumio[1338]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Mar 26 18:46:06 volumio volumio[1338]: at Socket.emit (node:events:514:28) Mar 26 18:46:06 volumio volumio[1338]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 26 18:46:06 volumio volumio[1338]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 26 18:46:06 volumio volumio[1338]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 18:46:08 volumio sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 18:45' Mar 26 18:46:08 volumio sudo[2144]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"