-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-10-05 21:26:56 UTC. -- Oct 05 21:25:37 volumio ntpd[830]: Soliciting pool server 2a0c:b847:ffff:79::e53c Oct 05 21:26:09 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.34 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 05 21:26:09 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 21:26:09 volumio volumio[1058]: info: Received Get System Info Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 21:26:09 volumio volumio[1058]: info: Discovery: Getting this device information Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 05 21:26:09 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 05 21:26:09 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:09 volumio volumio[1058]: info: Listing playlists Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::volumioGetQueue Oct 05 21:26:09 volumio volumio[1058]: info: CoreStateMachine::getQueue Oct 05 21:26:09 volumio volumio[1058]: info: CorePlayQueue::getQueue Oct 05 21:26:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 21:26:25 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 21:26:25 volumio volumio[1058]: info: CURURI: music-library/USB/New_Volume Oct 05 21:26:25 volumio volumio[1058]: info: Preload queue cleared Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:32 volumio volumio[1058]: info: Prefetching next song Oct 05 21:26:32 volumio volumio[1058]: info: DOING PREFETCH IN MPD Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand add "USB/USB/Tinh Hoai Huong - PhamDuy/02-Track02.flac" Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand add "USB/USB/Tinh Hoai Huong - PhamDuy/02-Track02.flac" took 2 milliseconds Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:32 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces state update: options Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand consume 1 took 5 milliseconds Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:32 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:32 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 10ms Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand status took 8 milliseconds Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 7ms Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 6ms Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces state update: options Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:32 volumio volumio[1058]: info: Oct 05 21:26:32 volumio volumio[1058]: ---------------------------- MPD announces state update: options Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand status took 10 milliseconds Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316061,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"508 Kbps","isStreaming":false,"title":"01-Track01.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/01-Track01.flac","trackType":"flac"} Oct 05 21:26:32 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:32 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 53ms Oct 05 21:26:32 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 05 21:26:32 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316061,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"508 Kbps","isStreaming":false,"title":"01-Track01.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/01-Track01.flac","trackType":"flac"} Oct 05 21:26:32 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:32 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316061,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"508 Kbps","isStreaming":false,"title":"01-Track01.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/01-Track01.flac","trackType":"flac"} Oct 05 21:26:32 volumio volumio[1058]: verbose: CURRENT POSITION 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:32 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:32 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 05 21:26:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 110ms Oct 05 21:26:32 volumio volumio[1058]: info: ------------------------------ 109ms Oct 05 21:26:36 volumio volumio[1058]: info: CoreStateMachine::startPlaybackTimer Oct 05 21:26:36 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:37 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces state update: player Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:37 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces state update: player Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Oct 05 21:26:37 volumio volumio[1058]: info: Ignoring MPD Status Update Oct 05 21:26:37 volumio volumio[1058]: info: Oct 05 21:26:37 volumio volumio[1058]: ---------------------------- MPD announces state update: player Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::getState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 101ms Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand status took 99 milliseconds Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 99ms Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand status took 100 milliseconds Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 100ms Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand status took 98 milliseconds Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseState Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 05 21:26:37 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Oct 05 21:26:37 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":315,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"02-Track02.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/02-Track02.flac","trackType":"flac"} Oct 05 21:26:37 volumio volumio[1058]: verbose: CURRENT POSITION 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:37 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":315,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"02-Track02.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/02-Track02.flac","trackType":"flac"} Oct 05 21:26:37 volumio volumio[1058]: verbose: CURRENT POSITION 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:37 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: ControllerMpd::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":315,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"02-Track02.flac","artist":"Various Artists","album":"Tinh Hoai Huong","uri":"USB/USB/Tinh Hoai Huong - PhamDuy/02-Track02.flac","trackType":"flac"} Oct 05 21:26:37 volumio volumio[1058]: verbose: CURRENT POSITION 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Oct 05 21:26:37 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:37 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:37 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 186ms Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 182ms Oct 05 21:26:37 volumio volumio[1058]: info: ------------------------------ 180ms Oct 05 21:26:40 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:0d:9f:da:2b:88 reason=0 locally_generated=1 Oct 05 21:26:40 volumio dhcpcd[1028]: wlan0: carrier lost Oct 05 21:26:40 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 05 21:26:40 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Oct 05 21:26:40 volumio avahi-daemon[681]: Withdrawing address record for 192.168.1.72 on wlan0. Oct 05 21:26:40 volumio avahi-daemon[681]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.72. Oct 05 21:26:40 volumio avahi-daemon[681]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 05 21:26:40 volumio dhcpcd[1028]: wlan0: deleting route to 192.168.1.0/24 Oct 05 21:26:40 volumio dhcpcd[1028]: wlan0: deleting default route via 192.168.1.1 Oct 05 21:26:40 volumio volumio[1058]: info: Discovery: A device disappeared from network Oct 05 21:26:40 volumio volumio[1058]: info: Discovery: Device volumio disappeared from network Oct 05 21:26:40 volumio wpa_supplicant[915]: wlan0: Trying to associate with SSID 'Ku Bi' Oct 05 21:26:41 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 05 21:26:42 volumio ntpd[830]: Deleting interface #4 wlan0, 192.168.1.72#123, interface stats: received=0, sent=0, dropped=0, active_time=710 secs Oct 05 21:26:42 volumio wpa_supplicant[915]: wlan0: Trying to associate with SSID 'Ku Bi' Oct 05 21:26:42 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 05 21:26:43 volumio ntpd[830]: Soliciting pool server 2401:5f80:5001:1f::31f Oct 05 21:26:44 volumio wpa_supplicant[915]: wlan0: Trying to associate with SSID 'Ku Bi' Oct 05 21:26:44 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 05 21:26:44 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Ku Bi" auth_failures=1 duration=10 reason=CONN_FAILED Oct 05 21:26:50 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Oct 05 21:26:55 volumio volumio[1058]: info: Preload queue cleared Oct 05 21:26:55 volumio volumio[1058]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::ClearQueue Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::stop Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::stPlaybackTimer Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::pushState Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:55 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 21:26:55 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::serviceStop Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::getTrack 1 Oct 05 21:26:55 volumio volumio[1058]: info: CoreCommandRouter::serviceStop Oct 05 21:26:55 volumio volumio[1058]: info: ControllerMpd::stop Oct 05 21:26:55 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::clearPlayQueue Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::saveQueue Oct 05 21:26:55 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Oct 05 21:26:55 volumio volumio[1058]: info: CoreStateMachine::addQueueItems Oct 05 21:26:55 volumio volumio[1058]: info: CorePlayQueue::addQueueItems Oct 05 21:26:55 volumio volumio[1058]: info: Preload queue cleared Oct 05 21:26:55 volumio volumio[1058]: info: Adding Item to queue: music-library/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM] Oct 05 21:26:55 volumio volumio[1058]: info: Exploding uri music-library/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM] in service mpd Oct 05 21:26:55 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Ku Bi" Oct 05 21:26:55 volumio wpa_supplicant[915]: wlan0: Trying to associate with SSID 'Ku Bi' Oct 05 21:26:56 volumio volumio[1058]: info: Oct 05 21:26:56 volumio volumio[1058]: ---------------------------- MPD announces state update: player Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F01%20%20-%20Track%201.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/01 - Track 1.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F02%20%20-%20Track%202.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/02 - Track 2.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F03%20%20-%20Track%203.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/03 - Track 3.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F04%20%20-%20Track%204.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/04 - Track 4.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F05%20%20-%20Track%205.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/05 - Track 5.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F06%20%20-%20Track%206.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/06 - Track 6.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F07%20%20-%20Track%207.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/07 - Track 7.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F08%20%20-%20Track%208.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/08 - Track 8.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2F09%20%20-%20Track%209.wav&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/09 - Track 9.wav Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2Fdrive-download-20240221T082619Z-001.zip&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/drive-download-20240221T082619Z-001.zip Oct 05 21:26:56 volumio volumio[1058]: info: ALBUMART /albumart?cacheid=931&web=//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FALBUM%20LOI%20CU%20TA%20VE%20%5BBAN%20THU%20LIVE%5D%20-%20%5BCD-SECURERIP-LUCPHAM%5D%2Fdrive-download-20240221T082619Z-001.zip&metadata=false Oct 05 21:26:56 volumio volumio[1058]: info: URI /mnt/USB/New_Volume/ALBUM LOI CU TA VE [BAN THU LIVE] - [CD-SECURERIP-LUCPHAM]/drive-download-20240221T082619Z-001.zip Oct 05 21:26:56 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 21:26:56 volumio volumio[1058]: Error: Unable to resolve or reject the same promise twice Oct 05 21:26:56 volumio volumio[1058]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 05 21:26:56 volumio volumio[1058]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 05 21:26:56 volumio volumio[1058]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 05 21:26:56 volumio volumio[1058]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 05 21:26:56 volumio volumio[1058]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 05 21:26:56 volumio volumio[1058]: at Socket.emit (events.js:315:20) Oct 05 21:26:56 volumio volumio[1058]: at addChunk (internal/streams/readable.js:309:12) Oct 05 21:26:56 volumio volumio[1058]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 05 21:26:56 volumio volumio[1058]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 05 21:26:56 volumio volumio[1058]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 05 21:26:56 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 21:26:56 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 05 21:26:56 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Ku Bi" auth_failures=2 duration=23 reason=CONN_FAILED Oct 05 21:26:56 volumio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 21:25 Oct 05 21:26:56 volumio sudo[2717]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"