-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Thu 2026-04-02 22:53:28 CST. -- Apr 02 22:52:00 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:02 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:02 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:02 volumio volumio[959]: info: Prefetching next song Apr 02 22:52:02 volumio volumio[959]: info: DOING PREFETCH IN MPD Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand add "USB/F22D-F923/万宝路/02- William Tell OvertureFinale (Lone Ranger Theme).wav" Apr 02 22:52:02 volumio volumio[959]: info: Apr 02 22:52:02 volumio volumio[959]: ---------------------------- MPD announces system playlist update Apr 02 22:52:02 volumio volumio[959]: info: Ignoring MPD Status Update Apr 02 22:52:02 volumio volumio[959]: info: sendMpdCommand add "USB/F22D-F923/万宝路/02- William Tell OvertureFinale (Lone Ranger Theme).wav" took 4 milliseconds Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 02 22:52:02 volumio volumio[959]: info: ------------------------------ 4ms Apr 02 22:52:02 volumio volumio[959]: info: Apr 02 22:52:02 volumio volumio[959]: ---------------------------- MPD announces state update: options Apr 02 22:52:02 volumio volumio[959]: info: sendMpdCommand consume 1 took 3 milliseconds Apr 02 22:52:02 volumio volumio[959]: info: ControllerMpd::getState Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Apr 02 22:52:02 volumio volumio[959]: info: sendMpdCommand status took 2 milliseconds Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::parseState Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 22:52:02 volumio volumio[959]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 22:52:02 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Apr 02 22:52:02 volumio volumio[959]: info: ControllerMpd::pushState Apr 02 22:52:02 volumio volumio[959]: info: CoreCommandRouter::servicePushState Apr 02 22:52:02 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:02 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49969,"duration":54,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sound Effects/Sounds of the West","artist":"Erich Kunzel","album":"Round-Up","uri":"USB/F22D-F923/万宝路/01- Sound EffectsSounds of the West.wav","trackType":"wav"} Apr 02 22:52:02 volumio volumio[959]: verbose: CURRENT POSITION 0 Apr 02 22:52:02 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Apr 02 22:52:02 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Apr 02 22:52:02 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Apr 02 22:52:02 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:02 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:02 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:02 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:02 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:02 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:02 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:02 volumio volumio[959]: info: ------------------------------ 36ms Apr 02 22:52:03 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:03 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:03 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:03 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:03 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:05 volumio volumio[959]: info: Apr 02 22:52:05 volumio volumio[959]: ---------------------------- MPD announces system playlist update Apr 02 22:52:05 volumio volumio[959]: info: Ignoring MPD Status Update Apr 02 22:52:05 volumio volumio[959]: info: Apr 02 22:52:05 volumio volumio[959]: ---------------------------- MPD announces state update: player Apr 02 22:52:05 volumio volumio[959]: info: ControllerMpd::getState Apr 02 22:52:05 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Apr 02 22:52:05 volumio volumio[959]: info: ------------------------------ 3ms Apr 02 22:52:05 volumio volumio[959]: info: sendMpdCommand status took 3 milliseconds Apr 02 22:52:05 volumio volumio[959]: verbose: ControllerMpd::parseState Apr 02 22:52:05 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 22:52:05 volumio volumio[959]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 22:52:05 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Apr 02 22:52:05 volumio volumio[959]: info: ControllerMpd::pushState Apr 02 22:52:05 volumio volumio[959]: info: CoreCommandRouter::servicePushState Apr 02 22:52:05 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:05 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"William Tell Overture/Finale (Lone Ranger Theme)","artist":"Erich Kunzel","album":"Round-Up","uri":"USB/F22D-F923/万宝路/02- William Tell OvertureFinale (Lone Ranger Theme).wav","trackType":"wav"} Apr 02 22:52:06 volumio volumio[959]: verbose: CURRENT POSITION 0 Apr 02 22:52:06 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Apr 02 22:52:06 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Apr 02 22:52:06 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Apr 02 22:52:06 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:06 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:06 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:06 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:06 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:06 volumio volumio[959]: info: CorePlayQueue::getTrack 0 Apr 02 22:52:06 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:06 volumio volumio[959]: info: ------------------------------ 32ms Apr 02 22:52:06 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Apr 02 22:52:06 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:07 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:07 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:07 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:07 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:07 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:07 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:07 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:07 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:07 volumio volumio[959]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Erich%20Kunzel/Round-Up/de4e5897-1ac1-4a86-b6f2-b56ee24b9d5c.jpg' Apr 02 22:52:08 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:08 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:08 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:08 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:08 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:08 volumio volumio[959]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Erich%20Kunzel/Round-Up/de4e5897-1ac1-4a86-b6f2-b56ee24b9d5c.jpg' Apr 02 22:52:09 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:14 volumio volumio[959]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Erich%20Kunzel/Round-Up/de4e5897-1ac1-4a86-b6f2-b56ee24b9d5c.jpg' Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 22:52:16 volumio volumio[959]: info: Discovery: Getting this device information Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Apr 02 22:52:16 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 22:52:16 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:16 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:16 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:16 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:17 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:21 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:21 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:21 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:21 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:21 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:25 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:25 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:25 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:25 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:26 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:31 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:31 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:31 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:31 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:31 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:31 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:31 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:31 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:31 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:31 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:32 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:32 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:32 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:32 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:32 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:34 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:34 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:34 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:34 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:34 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:34 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:34 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:34 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:34 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:34 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:34 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:36 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:36 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:36 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:36 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:36 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:42 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:48 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:48 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:48 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:48 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:48 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:51 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:52:55 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:52:55 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:55 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:55 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:55 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:56 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:52:56 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:52:56 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:52:56 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:52:56 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:52:59 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:53:01 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:53:01 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:01 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:01 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:01 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:04 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:53:05 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:05 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:05 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:53:05 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:05 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:05 volumio volumio[959]: info: VolumeController::SetAlsaVolume- Apr 02 22:53:05 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:05 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:05 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:08 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:53:16 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:53:19 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:53:19 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:19 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:19 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:19 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:20 volumio volumio[959]: info: VolumeController::SetAlsaVolume+ Apr 02 22:53:20 volumio volumio[959]: info: CoreStateMachine::pushState Apr 02 22:53:20 volumio volumio[959]: info: CorePlayQueue::getTrack 1 Apr 02 22:53:20 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 22:53:20 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Apr 02 22:53:25 volumio wpa_supplicant[847]: wlan0: Failed to initiate sched scan Apr 02 22:53:27 volumio volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 22:53:27 volumio volumio[959]: Error: connect ETIMEDOUT 199.96.58.157:443 Apr 02 22:53:27 volumio volumio[959]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 02 22:53:27 volumio volumio[959]: errno: -110, Apr 02 22:53:27 volumio volumio[959]: code: 'ETIMEDOUT', Apr 02 22:53:27 volumio volumio[959]: syscall: 'connect', Apr 02 22:53:27 volumio volumio[959]: address: '199.96.58.157', Apr 02 22:53:27 volumio volumio[959]: port: 443 Apr 02 22:53:27 volumio volumio[959]: } Apr 02 22:53:27 volumio volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 22:53:28 volumio sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 22:52 Apr 02 22:53:28 volumio sudo[3942]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"