-- Logs begin at Fri 2024-11-22 09:12:57 CST, end at Sat 2024-11-30 10:20:14 CST. -- Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:00 volumio volumio[884]: info: Nov 30 10:19:00 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:00 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:00 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:01 volumio volumio[884]: info: Nov 30 10:19:01 volumio volumio[884]: ---------------------------- MPD announces state update: player Nov 30 10:19:01 volumio volumio[884]: info: ControllerMpd::getState Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:19:01 volumio volumio[884]: info: sendMpdCommand status took 2 milliseconds Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::parseState Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:19:01 volumio volumio[884]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:19:01 volumio volumio[884]: info: ControllerMpd::pushState Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::servicePushState Nov 30 10:19:01 volumio volumio[884]: verbose: In UPNP mode Nov 30 10:19:01 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Das Model - Reeperbahn Festival Collide","artist":"Yin Yin","album":"Collide Session #8 - YIN YIN","uri":"http://aqqmusic.tc.qq.com/M800004C5QPe0nzNdG.mp3?guid=0000000072c051a3000001856ab3f5cf&vkey=E2B7D58A7031D2769A4B706C0314F7AB6E360C4096337804B04733743514840D7EADEE6A7686F54B6D312A57E2CBC7392CA281E02CFC90B4&uin=1152921504827565206&src=M800003bXo0j2hmk2C.mp3&redirect=1&fromtag=111093","trackType":""} Nov 30 10:19:01 volumio volumio[884]: verbose: CURRENT POSITION 0 Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::syncState stateService play Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus stop Nov 30 10:19:01 volumio volumio[884]: info: ------------------------------ 5ms Nov 30 10:19:01 volumio volumio[884]: info: Nov 30 10:19:01 volumio volumio[884]: ---------------------------- MPD announces system playlist update Nov 30 10:19:01 volumio volumio[884]: info: Ignoring MPD Status Update Nov 30 10:19:01 volumio volumio[884]: info: Nov 30 10:19:01 volumio volumio[884]: ---------------------------- MPD announces state update: player Nov 30 10:19:01 volumio volumio[884]: info: ControllerMpd::getState Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:19:01 volumio volumio[884]: info: ------------------------------ 1ms Nov 30 10:19:01 volumio volumio[884]: info: sendMpdCommand status took 2 milliseconds Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::parseState Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:19:01 volumio volumio[884]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 30 10:19:01 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:19:01 volumio volumio[884]: info: ControllerMpd::pushState Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::servicePushState Nov 30 10:19:01 volumio volumio[884]: verbose: In UPNP mode Nov 30 10:19:01 volumio volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":250,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Das Model - REEPERBAHN FESTIVAL COLLIDE","artist":"Yin Yin","album":"REEPERBAHN FESTIVAL COLLIDE","uri":"http://aqqmusic.tc.qq.com/M800004C5QPe0nzNdG.mp3?guid=0000000072c051a3000001856ab3f5cf&vkey=E2B7D58A7031D2769A4B706C0314F7AB6E360C4096337804B04733743514840D7EADEE6A7686F54B6D312A57E2CBC7392CA281E02CFC90B4&uin=1152921504827565206&src=M800003bXo0j2hmk2C.mp3&redirect=1&fromtag=111093","trackType":""} Nov 30 10:19:01 volumio volumio[884]: verbose: CURRENT POSITION 0 Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::syncState stateService play Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:19:01 volumio volumio[884]: info: Received an update from plugin. extracting info from payload Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:19:01 volumio volumio[884]: info: ------------------------------ 12ms Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:19:01 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:19:01 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:19:01 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:19:01 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:19:05 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:07 volumio volumio[884]: info: MyVolumio login type: Token Nov 30 10:19:11 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:17 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:23 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:29 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:35 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:37 volumio volumio[884]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 30 10:19:37 volumio volumio[884]: info: Retrying Login Due to Network Error Nov 30 10:19:40 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:46 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:47 volumio volumio[884]: info: MyVolumio login type: Token Nov 30 10:19:52 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:19:58 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:20:04 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:20:05 volumio volumio[884]: info: Nov 30 10:20:05 volumio volumio[884]: ---------------------------- MPD announces state update: player Nov 30 10:20:05 volumio volumio[884]: info: ControllerMpd::getState Nov 30 10:20:05 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:20:05 volumio volumio[884]: info: sendMpdCommand status took 1 milliseconds Nov 30 10:20:05 volumio volumio[884]: verbose: ControllerMpd::parseState Nov 30 10:20:05 volumio volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:20:05 volumio volumio[884]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 30 10:20:05 volumio volumio[884]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:20:05 volumio volumio[884]: info: ControllerMpd::pushState Nov 30 10:20:05 volumio volumio[884]: info: CoreCommandRouter::servicePushState Nov 30 10:20:05 volumio volumio[884]: verbose: In UPNP mode Nov 30 10:20:05 volumio volumio[884]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65485,"duration":250,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Das Model - REEPERBAHN FESTIVAL COLLIDE","artist":"Yin Yin","album":"REEPERBAHN FESTIVAL COLLIDE","uri":"http://aqqmusic.tc.qq.com/M800004C5QPe0nzNdG.mp3?guid=0000000072c051a3000001856ab3f5cf&vkey=E2B7D58A7031D2769A4B706C0314F7AB6E360C4096337804B04733743514840D7EADEE6A7686F54B6D312A57E2CBC7392CA281E02CFC90B4&uin=1152921504827565206&src=M800003bXo0j2hmk2C.mp3&redirect=1&fromtag=111093","trackType":""} Nov 30 10:20:05 volumio volumio[884]: verbose: CURRENT POSITION 0 Nov 30 10:20:05 volumio volumio[884]: info: CoreStateMachine::syncState stateService pause Nov 30 10:20:05 volumio volumio[884]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:20:05 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:05 volumio volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:20:05 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:05 volumio volumio[884]: info: CoreStateMachine::stPlaybackTimer Nov 30 10:20:05 volumio volumio[884]: info: ------------------------------ 6ms Nov 30 10:20:05 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:05 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:05 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:10 volumio volumio[1072]: 90 Nov 30 10:20:10 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:10 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:10 volumio volumio[884]: info: Nov 30 10:20:10 volumio volumio[884]: ---------------------------- Client requests Volume 90 Nov 30 10:20:10 volumio volumio[884]: info: VolumeController::SetAlsaVolume90 Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:10 volumio volumio[1072]: [158B blob data] Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:10 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:10 volumio volumio[1072]: {"time":1732933210425,"response":"volume Success"}90 Nov 30 10:20:10 volumio volumio[1072]: 80 Nov 30 10:20:10 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:10 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:10 volumio volumio[884]: info: Nov 30 10:20:10 volumio volumio[884]: ---------------------------- Client requests Volume 80 Nov 30 10:20:10 volumio volumio[884]: info: VolumeController::SetAlsaVolume80 Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:10 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:10 volumio volumio[1072]: [158B blob data] Nov 30 10:20:10 volumio volumio[1072]: {"time":1732933210522,"response":"volume Success"}80 Nov 30 10:20:10 volumio volumio[1072]: 70 Nov 30 10:20:10 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:10 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:10 volumio volumio[884]: info: Nov 30 10:20:10 volumio volumio[884]: ---------------------------- Client requests Volume 70 Nov 30 10:20:10 volumio volumio[884]: info: VolumeController::SetAlsaVolume70 Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:10 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:10 volumio volumio[1072]: [158B blob data] Nov 30 10:20:10 volumio volumio[1072]: {"time":1732933210616,"response":"volume Success"}70 Nov 30 10:20:10 volumio volumio[1072]: 60 Nov 30 10:20:10 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:10 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:10 volumio volumio[884]: info: Nov 30 10:20:10 volumio volumio[884]: ---------------------------- Client requests Volume 60 Nov 30 10:20:10 volumio volumio[884]: info: VolumeController::SetAlsaVolume60 Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:10 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:10 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:10 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:10 volumio volumio[1072]: [158B blob data] Nov 30 10:20:10 volumio volumio[1072]: {"time":1732933210736,"response":"volume Success"}60 Nov 30 10:20:10 volumio wpa_supplicant[713]: wlan0: Failed to initiate sched scan Nov 30 10:20:12 volumio volumio[1072]: 50 Nov 30 10:20:12 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:12 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:12 volumio volumio[884]: info: Nov 30 10:20:12 volumio volumio[884]: ---------------------------- Client requests Volume 50 Nov 30 10:20:12 volumio volumio[884]: info: VolumeController::SetAlsaVolume50 Nov 30 10:20:12 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:12 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:12 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:12 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:12 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:12 volumio volumio[1072]: [158B blob data] Nov 30 10:20:12 volumio volumio[1072]: {"time":1732933212175,"response":"volume Success"}50 Nov 30 10:20:13 volumio volumio[1072]: 40 Nov 30 10:20:13 volumio volumio[1072]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 30 10:20:13 volumio volumio[1072]: Dload Upload Total Spent Left Speed Nov 30 10:20:13 volumio volumio[884]: info: Nov 30 10:20:13 volumio volumio[884]: ---------------------------- Client requests Volume 40 Nov 30 10:20:13 volumio volumio[884]: info: VolumeController::SetAlsaVolume40 Nov 30 10:20:13 volumio volumio[884]: info: CoreStateMachine::pushState Nov 30 10:20:13 volumio volumio[884]: info: CoreCommandRouter::volumioPushState Nov 30 10:20:13 volumio volumio[884]: info: CoreCommandRouter::volumioGetQueue Nov 30 10:20:13 volumio volumio[884]: info: CoreStateMachine::getQueue Nov 30 10:20:13 volumio volumio[884]: info: CorePlayQueue::getQueue Nov 30 10:20:13 volumio volumio[1072]: [158B blob data] Nov 30 10:20:13 volumio volumio[1072]: {"time":1732933213235,"response":"volume Success"}40 Nov 30 10:20:14 volumio volumio[884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 10:20:14 volumio volumio[884]: Error: connect ETIMEDOUT 185.45.6.103:443 Nov 30 10:20:14 volumio volumio[884]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 30 10:20:14 volumio volumio[884]: errno: -110, Nov 30 10:20:14 volumio volumio[884]: code: 'ETIMEDOUT', Nov 30 10:20:14 volumio volumio[884]: syscall: 'connect', Nov 30 10:20:14 volumio volumio[884]: address: '185.45.6.103', Nov 30 10:20:14 volumio volumio[884]: port: 443 Nov 30 10:20:14 volumio volumio[884]: } Nov 30 10:20:14 volumio volumio[884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 10:20:14 volumio sudo[18663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 10:19 Nov 30 10:20:14 volumio sudo[18663]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"