-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2025-05-16 11:36:11 CST. -- May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::pause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::stPlaybackTimer May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::servicePause May 16 11:35:01 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::servicePause May 16 11:35:01 volumio-rpi volumio[9911]: info: ControllerMpd::pause May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand pause May 16 11:35:01 volumio-rpi volumio[9911]: info: May 16 11:35:01 volumio-rpi volumio[9911]: ---------------------------- MPD announces state update: player May 16 11:35:01 volumio-rpi volumio[9911]: info: sendMpdCommand pause took 5 milliseconds May 16 11:35:01 volumio-rpi volumio[9911]: info: ControllerMpd::getState May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand status May 16 11:35:01 volumio-rpi volumio[9911]: info: May 16 11:35:01 volumio-rpi volumio[9911]: ---------------------------- MPD announces state update: player May 16 11:35:01 volumio-rpi volumio[9911]: info: ControllerMpd::getState May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand status May 16 11:35:01 volumio-rpi volumio[9911]: info: sendMpdCommand status took 7 milliseconds May 16 11:35:01 volumio-rpi volumio[9911]: info: sendMpdCommand status took 4 milliseconds May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseState May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseState May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 11:35:01 volumio-rpi volumio[9911]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 11:35:01 volumio-rpi volumio[9911]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseTrackInfo May 16 11:35:01 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseTrackInfo May 16 11:35:01 volumio-rpi volumio[9911]: info: ControllerMpd::pushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::servicePushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:01 volumio-rpi volumio[9911]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14662,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"午夜怨曲","artist":"BEYOND","album":"真的见证","uri":"INTERNAL/musics/20-BEYOND专辑 42首/10-BEYOND - 午夜怨曲.mp3","trackType":"mp3"} May 16 11:35:01 volumio-rpi volumio[9911]: verbose: CURRENT POSITION 57 May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState stateService pause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState currentStatus pause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::stPlaybackTimer May 16 11:35:01 volumio-rpi volumio[9911]: info: ControllerMpd::pushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::servicePushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:01 volumio-rpi volumio[9911]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14662,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"午夜怨曲","artist":"BEYOND","album":"真的见证","uri":"INTERNAL/musics/20-BEYOND专辑 42首/10-BEYOND - 午夜怨曲.mp3","trackType":"mp3"} May 16 11:35:01 volumio-rpi volumio[9911]: verbose: CURRENT POSITION 57 May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState stateService pause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState currentStatus pause May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:01 volumio-rpi volumio[9911]: info: CoreStateMachine::stPlaybackTimer May 16 11:35:01 volumio-rpi volumio[9911]: info: ------------------------------ 135ms May 16 11:35:01 volumio-rpi volumio[9911]: info: ------------------------------ 134ms May 16 11:35:02 volumio-rpi volumio[9911]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E7%9C%9F%E7%9A%84%E8%A7%81%E8%AF%81/3b2842de-c2c4-4289-b91f-568d0567926c.jpg' May 16 11:35:02 volumio-rpi volumio[9911]: info: touch_display: Setting screensaver timeout to 60 seconds. May 16 11:35:02 volumio-rpi volumio[9911]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E7%9C%9F%E7%9A%84%E8%A7%81%E8%AF%81/3b2842de-c2c4-4289-b91f-568d0567926c.jpg' May 16 11:35:03 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 11:35:03 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 11:35:03 volumio-rpi volumio[9911]: info: Discovery: Getting this device information May 16 11:35:03 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioGetState May 16 11:35:03 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:03 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 11:35:05 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:10 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:16 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:22 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:27 volumio-rpi irexec[10299]: {"time":1747366487394,"response":"next Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 16 11:35:27 volumio-rpi irexec[10299]: Dload Upload Total Spent Left Speed May 16 11:35:27 volumio-rpi volumio[9911]: info: May 16 11:35:27 volumio-rpi volumio[9911]: ---------------------------- Client requests Volume toggle May 16 11:35:27 volumio-rpi volumio[9911]: info: VolumeController::SetAlsaVolumetoggle May 16 11:35:27 volumio-rpi volumio[9911]: info: VolumeController::SetAlsaVolumemute May 16 11:35:27 volumio-rpi irexec[10299]: [158B blob data] May 16 11:35:27 volumio-rpi volumio[9911]: Unable to parse: May 16 11:35:27 volumio-rpi volumio[9911]: Simple mixer control 'SoftMaster',0 May 16 11:35:27 volumio-rpi volumio[9911]: Capabilities: volume May 16 11:35:27 volumio-rpi volumio[9911]: Playback channels: Front Left - Front Right May 16 11:35:27 volumio-rpi volumio[9911]: Capture channels: Front Left - Front Right May 16 11:35:27 volumio-rpi volumio[9911]: Limits: 0 - 99 May 16 11:35:27 volumio-rpi volumio[9911]: Front Left: 80 [81%] May 16 11:35:27 volumio-rpi volumio[9911]: Front Right: 80 [81%] May 16 11:35:27 volumio-rpi volumio[9911]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 16 11:35:27 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:27 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:27 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 11:35:27 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:27 volumio-rpi volumio[9911]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E7%9C%9F%E7%9A%84%E8%A7%81%E8%AF%81/3b2842de-c2c4-4289-b91f-568d0567926c.jpg' May 16 11:35:27 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:28 volumio-rpi volumio[9911]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 16 11:35:28 volumio-rpi volumio[9911]: info: Retrying Login Due to Network Error May 16 11:35:33 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:33 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 11:35:33 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 11:35:33 volumio-rpi volumio[9911]: info: Discovery: Getting this device information May 16 11:35:33 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioGetState May 16 11:35:33 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:33 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 11:35:38 volumio-rpi volumio[9911]: info: MyVolumio login type: Token May 16 11:35:38 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:41 volumio-rpi irexec[10299]: {"time":1747366527105,"response":"volume Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 16 11:35:41 volumio-rpi irexec[10299]: Dload Upload Total Spent Left Speed May 16 11:35:41 volumio-rpi volumio[9911]: info: May 16 11:35:41 volumio-rpi volumio[9911]: ---------------------------- Client requests Volume toggle May 16 11:35:41 volumio-rpi volumio[9911]: info: VolumeController::SetAlsaVolumetoggle May 16 11:35:41 volumio-rpi volumio[9911]: info: VolumeController::SetAlsaVolumeunmute May 16 11:35:41 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:41 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:41 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 11:35:41 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:41 volumio-rpi irexec[10299]: [158B blob data] May 16 11:35:44 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:47 volumio-rpi irexec[10299]: {"time":1747366541916,"response":"volume Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 16 11:35:47 volumio-rpi irexec[10299]: Dload Upload Total Spent Left Speed May 16 11:35:47 volumio-rpi volumio[9911]: info: May 16 11:35:47 volumio-rpi volumio[9911]: ---------------------------- Client requests Volumio toggle May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioToggle May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::play index undefined May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::startPlaybackTimer May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: ControllerMpd::resume May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand play May 16 11:35:47 volumio-rpi irexec[10299]: [158B blob data] May 16 11:35:47 volumio-rpi volumio[9911]: info: May 16 11:35:47 volumio-rpi volumio[9911]: ---------------------------- MPD announces state update: player May 16 11:35:47 volumio-rpi volumio[9911]: info: sendMpdCommand play took 60 milliseconds May 16 11:35:47 volumio-rpi volumio[9911]: info: ControllerMpd::getState May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand status May 16 11:35:47 volumio-rpi kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 16 11:35:47 volumio-rpi volumio[9911]: info: May 16 11:35:47 volumio-rpi volumio[9911]: ---------------------------- MPD announces state update: player May 16 11:35:47 volumio-rpi volumio[9911]: info: ControllerMpd::getState May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand status May 16 11:35:47 volumio-rpi volumio[9911]: info: sendMpdCommand status took 10 milliseconds May 16 11:35:47 volumio-rpi volumio[9911]: info: sendMpdCommand status took 6 milliseconds May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseState May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseState May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 11:35:47 volumio-rpi volumio[9911]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 11:35:47 volumio-rpi volumio[9911]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseTrackInfo May 16 11:35:47 volumio-rpi volumio[9911]: verbose: ControllerMpd::parseTrackInfo May 16 11:35:47 volumio-rpi volumio[9911]: info: ControllerMpd::pushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::servicePushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15405,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"午夜怨曲","artist":"BEYOND","album":"真的见证","uri":"INTERNAL/musics/20-BEYOND专辑 42首/10-BEYOND - 午夜怨曲.mp3","trackType":"mp3"} May 16 11:35:47 volumio-rpi volumio[9911]: verbose: CURRENT POSITION 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState stateService play May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState currentStatus pause May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:47 volumio-rpi volumio[9911]: info: ControllerMpd::pushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::servicePushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15405,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"午夜怨曲","artist":"BEYOND","album":"真的见证","uri":"INTERNAL/musics/20-BEYOND专辑 42首/10-BEYOND - 午夜怨曲.mp3","trackType":"mp3"} May 16 11:35:47 volumio-rpi volumio[9911]: verbose: CURRENT POSITION 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState stateService play May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::syncState currentStatus play May 16 11:35:47 volumio-rpi volumio[9911]: info: Received an update from plugin. extracting info from payload May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreStateMachine::pushState May 16 11:35:47 volumio-rpi volumio[9911]: info: CorePlayQueue::getTrack 57 May 16 11:35:47 volumio-rpi volumio[9911]: info: CoreCommandRouter::volumioPushState May 16 11:35:47 volumio-rpi volumio[9911]: info: ------------------------------ 126ms May 16 11:35:47 volumio-rpi volumio[9911]: info: ------------------------------ 123ms May 16 11:35:47 volumio-rpi volumio[9911]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 11:35:50 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:35:55 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:36:01 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:36:07 volumio-rpi wpa_supplicant[999]: wlan0: Failed to initiate sched scan May 16 11:36:08 volumio-rpi volumio[9911]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 16 11:36:08 volumio-rpi volumio[9911]: info: Retrying Login Due to Network Error May 16 11:36:10 volumio-rpi volumio[9911]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 11:36:10 volumio-rpi volumio[9911]: Error: connect ETIMEDOUT 185.45.7.165:443 May 16 11:36:10 volumio-rpi volumio[9911]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 16 11:36:10 volumio-rpi volumio[9911]: errno: -110, May 16 11:36:10 volumio-rpi volumio[9911]: code: 'ETIMEDOUT', May 16 11:36:10 volumio-rpi volumio[9911]: syscall: 'connect', May 16 11:36:10 volumio-rpi volumio[9911]: address: '185.45.7.165', May 16 11:36:10 volumio-rpi volumio[9911]: port: 443 May 16 11:36:10 volumio-rpi volumio[9911]: } May 16 11:36:10 volumio-rpi volumio[9911]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 11:36:11 volumio-rpi sudo[12468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 11:35 May 16 11:36:11 volumio-rpi sudo[12468]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"