-- Logs begin at Sat 2024-10-05 10:09:39 CST, end at Sat 2024-10-05 10:12:39 CST. -- Oct 05 10:11:02 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 10:11:02 volumio volumio[1219]: info: CURURI: music-library/USB Oct 05 10:11:02 volumio volumio[1219]: info: Preload queue cleared Oct 05 10:11:02 volumio volumio[1219]: info: Preloading song: music-library/USB/Kiroro - 長い間.flac Oct 05 10:11:02 volumio volumio[1219]: info: Preloading song: music-library/USB/Kiroro - 未来へ.flac Oct 05 10:11:02 volumio volumio[1219]: info: Preloading song: music-library/USB/Richard Sanderson - Reality.flac Oct 05 10:11:02 volumio volumio[1219]: info: Preloading song: music-library/USB/THE虎舞竜 - ロード.flac Oct 05 10:11:02 volumio volumio[1219]: info: Preloading song: music-library/USB/蔡琴 - 恰似你的温柔.flac Oct 05 10:11:02 volumio volumio[1219]: info: Exploding uri music-library/USB/Kiroro - 長い間.flac in service mpd Oct 05 10:11:02 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=851&web=Kiroro/Nagai%20Aida%20%EF%BD%9EKiroro%20no%20Mori%EF%BD%9E/extralarge&path=%2Fmnt%2FUSB%2FKiroro%20-%20%E9%95%B7%E3%81%84%E9%96%93.flac&metadata=false Oct 05 10:11:02 volumio volumio[1219]: info: URI /mnt/USB/Kiroro - 長い間.flac Oct 05 10:11:02 volumio volumio[1219]: info: Exploding uri music-library/USB/Kiroro - 未来へ.flac in service mpd Oct 05 10:11:02 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=851&web=Kiroro/Nagai%20Aida%20%EF%BD%9EKiroro%20no%20Mori%EF%BD%9E/extralarge&path=%2Fmnt%2FUSB%2FKiroro%20-%20%E6%9C%AA%E6%9D%A5%E3%81%B8.flac&metadata=false Oct 05 10:11:02 volumio volumio[1219]: info: URI /mnt/USB/Kiroro - 未来へ.flac Oct 05 10:11:02 volumio volumio[1219]: info: Exploding uri music-library/USB/Richard Sanderson - Reality.flac in service mpd Oct 05 10:11:02 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=851&web=Richard%20Sanderson/Reality/extralarge&path=%2Fmnt%2FUSB%2FRichard%20Sanderson%20-%20Reality.flac&metadata=false Oct 05 10:11:02 volumio volumio[1219]: info: URI /mnt/USB/Richard Sanderson - Reality.flac Oct 05 10:11:02 volumio volumio[1219]: info: Exploding uri music-library/USB/THE虎舞竜 - ロード.flac in service mpd Oct 05 10:11:02 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=851&web=THE%E8%99%8E%E8%88%9E%E7%AB%9C/%E3%83%AD%E3%83%BC%E3%83%89~%E3%83%89%E3%82%AD%E3%83%A5%E3%83%A1%E3%83%B3%E3%83%88/extralarge&path=%2Fmnt%2FUSB%2FTHE%E8%99%8E%E8%88%9E%E7%AB%9C%20-%20%E3%83%AD%E3%83%BC%E3%83%89.flac&metadata=false Oct 05 10:11:02 volumio volumio[1219]: info: URI /mnt/USB/THE虎舞竜 - ロード.flac Oct 05 10:11:02 volumio volumio[1219]: info: Exploding uri music-library/USB/蔡琴 - 恰似你的温柔.flac in service mpd Oct 05 10:11:02 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=851&web=%E8%94%A1%E7%90%B4/%E7%B2%BE%E9%80%89%E8%94%A1%E7%90%B4/extralarge&path=%2Fmnt%2FUSB%2F%E8%94%A1%E7%90%B4%20-%20%E6%81%B0%E4%BC%BC%E4%BD%A0%E7%9A%84%E6%B8%A9%E6%9F%94.flac&metadata=false Oct 05 10:11:02 volumio volumio[1219]: info: URI /mnt/USB/蔡琴 - 恰似你的温柔.flac Oct 05 10:11:07 volumio volumio[1219]: info: MyVolumio login type: Token Oct 05 10:11:07 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:08 volumio ntpd[1277]: Soliciting pool server 95.111.202.5 Oct 05 10:11:09 volumio ntpd[1277]: receive: Unexpected origin timestamp 0xeaab1ebc.42c05442 does not match aorg 0000000000.00000000 from server@84.16.67.12 xmt 0xeaab1ebd.82da4e70 Oct 05 10:11:10 volumio ntpd[1277]: Soliciting pool server 193.182.111.12 Oct 05 10:11:11 volumio ntpd[1277]: Soliciting pool server 2a03:8600::aa Oct 05 10:11:11 volumio ntpd[1277]: Soliciting pool server 193.182.111.141 Oct 05 10:11:18 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 10:11:18 volumio volumio[1219]: info: CURURI: music-library Oct 05 10:11:18 volumio volumio[1219]: info: Preload queue cleared Oct 05 10:11:19 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 10:11:19 volumio volumio[1219]: info: CURURI: music-library/USB Oct 05 10:11:19 volumio volumio[1219]: info: Preload queue cleared Oct 05 10:11:19 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:27 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:27 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:30 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:36 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 10:11:36 volumio volumio[1219]: info: CURURI: music-library Oct 05 10:11:36 volumio volumio[1219]: info: Preload queue cleared Oct 05 10:11:37 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 10:11:37 volumio volumio[1219]: info: CURURI: music-library/USB Oct 05 10:11:37 volumio volumio[1219]: info: Preload queue cleared Oct 05 10:11:38 volumio volumio[1219]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 05 10:11:38 volumio volumio[1219]: info: Retrying Login Due to Network Error Oct 05 10:11:41 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:41 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:48 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:48 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:48 volumio volumio[1219]: info: MyVolumio login type: Token Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: info: Retrieving Cloud Streaming UI Oct 05 10:11:51 volumio volumio[1219]: info: Getting Tidal Cloud Configuration Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: info: Getting Qobuz Cloud Configuration Oct 05 10:11:51 volumio volumio[1219]: info: Asking plugin for UI Config Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: info: Getting Spotify Cloud Configuration Oct 05 10:11:51 volumio volumio[1219]: info: Asking plugin for UI Config Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: info: Saving Spotify Acccount Oct 05 10:11:51 volumio volumio[1219]: info: Got Tidal Cloud Configuration Oct 05 10:11:51 volumio volumio[1219]: info: Got it Oct 05 10:11:51 volumio volumio[1219]: info: Got it Oct 05 10:11:51 volumio volumio[1219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 10:11:51 volumio volumio[1219]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 10:11:51 volumio volumio[1219]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 10:11:52 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:11:52 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:11:52 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:11:52 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 05 10:11:56 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:03 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:12:03 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:04 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand rescan Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: info: Oct 05 10:12:07 volumio volumio[1219]: ---------------------------- MPD announces state update: update Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand rescan took 29 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::getState Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: info: Oct 05 10:12:07 volumio volumio[1219]: ---------------------------- MPD announces state update: update Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::getState Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: info: Oct 05 10:12:07 volumio volumio[1219]: ---------------------------- MPD announces state update: update Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::getState Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: info: Oct 05 10:12:07 volumio volumio[1219]: ---------------------------- MPD announces state update: update Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::getState Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 70 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 66 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 63 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 61 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 55 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 51 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 49 milliseconds Oct 05 10:12:07 volumio volumio[1219]: info: sendMpdCommand status took 45 milliseconds Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: info: Command Router : Notfying DB Updatetrue Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: info: Command Router : Notfying DB Updatetrue Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::Close All Modals sent Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: info: Command Router : Notfying DB Updatetrue Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::Close All Modals sent Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: info: Command Router : Notfying DB Updatetrue Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::Close All Modals sent Oct 05 10:12:07 volumio volumio[1219]: verbose: ControllerMpd::parseState Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: 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} Oct 05 10:12:07 volumio volumio[1219]: verbose: CURRENT POSITION 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState stateService stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: No code Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: 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} Oct 05 10:12:07 volumio volumio[1219]: verbose: CURRENT POSITION 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState stateService stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: No code Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: 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} Oct 05 10:12:07 volumio volumio[1219]: verbose: CURRENT POSITION 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState stateService stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: No code Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: ControllerMpd::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: 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} Oct 05 10:12:07 volumio volumio[1219]: verbose: CURRENT POSITION 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState stateService stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: No code Oct 05 10:12:07 volumio volumio[1219]: info: CoreStateMachine::pushState Oct 05 10:12:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Oct 05 10:12:07 volumio volumio[1219]: info: ------------------------------ 451ms Oct 05 10:12:07 volumio volumio[1219]: info: ------------------------------ 429ms Oct 05 10:12:07 volumio volumio[1219]: info: ------------------------------ 422ms Oct 05 10:12:07 volumio volumio[1219]: info: ------------------------------ 418ms Oct 05 10:12:08 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:12 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:15 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:12:15 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:16 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 10:12:18 volumio volumio[1219]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 05 10:12:18 volumio volumio[1219]: info: Retrying Login Due to Network Error Oct 05 10:12:21 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 05 10:12:23 volumio dhcpcd[870]: wlan0: carrier lost Oct 05 10:12:23 volumio avahi-daemon[774]: Withdrawing address record for 192.168.31.96 on wlan0. Oct 05 10:12:23 volumio avahi-daemon[774]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.96. Oct 05 10:12:23 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-DISCONNECTED bssid=88:c3:97:32:0a:ef reason=4 locally_generated=1 Oct 05 10:12:23 volumio avahi-daemon[774]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 05 10:12:23 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 05 10:12:23 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 05 10:12:23 volumio volumio[1219]: info: Discovery: A device disappeared from network Oct 05 10:12:23 volumio volumio[1219]: info: Discovery: Device volumio disappeared from network Oct 05 10:12:23 volumio dhcpcd[870]: wlan0: deleting route to 192.168.31.0/24 Oct 05 10:12:23 volumio dhcpcd[870]: wlan0: deleting default route via 192.168.31.1 Oct 05 10:12:25 volumio ntpd[1277]: Deleting interface #3 wlan0, 192.168.31.96#123, interface stats: received=55, sent=95, dropped=0, active_time=146 secs Oct 05 10:12:25 volumio ntpd[1277]: 95.111.202.5 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 193.182.111.143 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 108.59.2.24 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 139.199.214.202 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 84.16.67.12 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 162.159.200.123 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 193.182.111.142 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 119.28.206.193 local addr 192.168.31.96 -> Oct 05 10:12:25 volumio ntpd[1277]: 202.112.29.82 local addr 192.168.31.96 -> Oct 05 10:12:26 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:12:26 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:28 volumio volumio[1219]: info: MyVolumio login type: Token Oct 05 10:12:28 volumio volumio[1219]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 05 10:12:28 volumio volumio[1219]: info: Retrying Login Due to Network Error Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: SME: Trying to authenticate with 88:c3:97:32:0a:f0 (SSID='Xiaomi_4272' freq=2412 MHz) Oct 05 10:12:31 volumio kernel: wlan0: authenticate with 88:c3:97:32:0a:f0 Oct 05 10:12:31 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Oct 05 10:12:31 volumio kernel: wlan0: send auth to 88:c3:97:32:0a:f0 (try 1/3) Oct 05 10:12:31 volumio kernel: wlan0: send auth to 88:c3:97:32:0a:f0 (try 2/3) Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: Trying to associate with 88:c3:97:32:0a:f0 (SSID='Xiaomi_4272' freq=2412 MHz) Oct 05 10:12:31 volumio kernel: wlan0: authenticated Oct 05 10:12:31 volumio kernel: wlan0: associate with 88:c3:97:32:0a:f0 (try 1/3) Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: Associated with 88:c3:97:32:0a:f0 Oct 05 10:12:31 volumio kernel: wlan0: RX AssocResp from 88:c3:97:32:0a:f0 (capab=0x1031 status=0 aid=5) Oct 05 10:12:31 volumio kernel: wlan0: associated Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: WPA: Key negotiation completed with 88:c3:97:32:0a:f0 [PTK=CCMP GTK=TKIP] Oct 05 10:12:31 volumio wpa_supplicant[1135]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:c3:97:32:0a:f0 completed [id=0 id_str=] Oct 05 10:12:31 volumio dhcpcd[870]: wlan0: carrier acquired Oct 05 10:12:31 volumio dhcpcd[870]: wlan0: IAID 10:69:fa:ce Oct 05 10:12:31 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 88:c3:97:32:0a:f0 Oct 05 10:12:31 volumio dhcpcd[870]: wlan0: rebinding lease of 192.168.31.96 Oct 05 10:12:32 volumio dhcpcd[870]: wlan0: soliciting an IPv6 router Oct 05 10:12:32 volumio dhcpcd[870]: wlan0: probing address 192.168.31.96/24 Oct 05 10:12:37 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:12:37 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:37 volumio dhcpcd[870]: wlan0: leased 192.168.31.96 for 43200 seconds Oct 05 10:12:37 volumio dhcpcd[870]: wlan0: adding route to 192.168.31.0/24 Oct 05 10:12:37 volumio dhcpcd[870]: wlan0: adding default route via 192.168.31.1 Oct 05 10:12:37 volumio avahi-daemon[774]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.96. Oct 05 10:12:37 volumio avahi-daemon[774]: New relevant interface wlan0.IPv4 for mDNS. Oct 05 10:12:37 volumio avahi-daemon[774]: Registering new address record for 192.168.31.96 on wlan0.IPv4. Oct 05 10:12:37 volumio ntpd[1277]: ntpd exiting on signal 15 (Terminated) Oct 05 10:12:37 volumio systemd[1]: Stopping Network Time Service... Oct 05 10:12:37 volumio systemd[1]: ntp.service: Succeeded. Oct 05 10:12:37 volumio systemd[1]: Stopped Network Time Service. Oct 05 10:12:37 volumio systemd[1]: Starting Network Time Service... Oct 05 10:12:37 volumio ntpd[2343]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 10:12:37 volumio ntpd[2343]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 05 10:12:37 volumio systemd[1]: Started Network Time Service. Oct 05 10:12:37 volumio ntpd[2349]: proto: precision = 0.199 usec (-22) Oct 05 10:12:37 volumio ntpd[2349]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 10:12:37 volumio ntpd[2349]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 10:12:37 volumio ntpd[2349]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 648 days ago Oct 05 10:12:37 volumio ntpd[2349]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 10:12:37 volumio ntpd[2349]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 10:12:37 volumio ntpd[2349]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 10:12:37 volumio ntpd[2349]: Listen normally on 3 wlan0 192.168.31.96:123 Oct 05 10:12:37 volumio ntpd[2349]: Listening on routing socket on fd #20 for interface updates Oct 05 10:12:37 volumio ntpd[2349]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 10:12:37 volumio ntpd[2349]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 10:12:38 volumio volumio[1219]: info: MyVolumio login type: Token Oct 05 10:12:38 volumio volumio[1219]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Oct 05 10:12:38 volumio volumio[1219]: info: Discovery: Found device Volumio Oct 05 10:12:38 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Oct 05 10:12:38 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Oct 05 10:12:38 volumio ntpd[2349]: Soliciting pool server 78.46.102.180 Oct 05 10:12:39 volumio volumio[1219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 10:12:39 volumio volumio[1219]: Error: connect ETIMEDOUT 108.160.162.104:443 Oct 05 10:12:39 volumio volumio[1219]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 05 10:12:39 volumio volumio[1219]: errno: -110, Oct 05 10:12:39 volumio volumio[1219]: code: 'ETIMEDOUT', Oct 05 10:12:39 volumio volumio[1219]: syscall: 'connect', Oct 05 10:12:39 volumio volumio[1219]: address: '108.160.162.104', Oct 05 10:12:39 volumio volumio[1219]: port: 443 Oct 05 10:12:39 volumio volumio[1219]: } Oct 05 10:12:39 volumio volumio[1219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 10:12:39 volumio sudo[2361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 10:11 Oct 05 10:12:39 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"