-- Logs begin at Wed 2024-01-24 14:46:15 UTC, end at Sat 2024-10-26 14:42:26 UTC. -- Oct 26 14:41:06 volumio volumio[907]: info: MyVolumio login type: Token Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::ClearQueue Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::stop Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::clearPlayQueue Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::saveQueue Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::addQueueItems Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::addQueueItems Oct 26 14:41:16 volumio volumio[907]: info: Adding Item to queue: albums://Adele/21 Oct 26 14:41:16 volumio volumio[907]: info: Exploding uri albums://Adele/21 in service mpd Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::saveQueue Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::updateTrackBlock Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrackBlock Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPlay Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::play index 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::stop Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::play index undefined Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::startPlaybackTimer Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::clearAddPlayTracks NAS/共享文件/音乐/@Apple Music Top100/[015]Adele - 21 (2011) [ALAC 16B-44.1kHz]/01 Rolling in the Deep.m4a Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand stop took 3 milliseconds Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 14:41:16 volumio volumio[907]: info: Oct 26 14:41:16 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 26 14:41:16 volumio volumio[907]: info: Ignoring MPD Status Update Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand clear took 5 milliseconds Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand add "NAS/共享文件/音乐/@Apple Music Top100/[015]Adele - 21 (2011) [ALAC 16B-44.1kHz]/01 Rolling in the Deep.m4a" Oct 26 14:41:16 volumio volumio[907]: error: updateQueue error: null Oct 26 14:41:16 volumio volumio[907]: info: Oct 26 14:41:16 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 26 14:41:16 volumio volumio[907]: info: Ignoring MPD Status Update Oct 26 14:41:16 volumio volumio[907]: info: ------------------------------ 7ms Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand add "NAS/共享文件/音乐/@Apple Music Top100/[015]Adele - 21 (2011) [ALAC 16B-44.1kHz]/01 Rolling in the Deep.m4a" took 5 milliseconds Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand play Oct 26 14:41:16 volumio volumio[907]: info: ------------------------------ 9ms Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand play took 6 milliseconds Oct 26 14:41:16 volumio volumio[907]: error: Upnp client error: Error: This socket has been ended by the other party Oct 26 14:41:16 volumio volumio[907]: info: Oct 26 14:41:16 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 26 14:41:16 volumio volumio[907]: info: ControllerMpd::getState Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:41:16 volumio volumio[907]: info: Oct 26 14:41:16 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand status took 51 milliseconds Oct 26 14:41:16 volumio volumio[907]: info: ControllerMpd::getState Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand status took 13 milliseconds Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:41:16 volumio volumio[907]: info: ControllerMpd::pushState Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"960 Kbps","isStreaming":false,"title":"Rolling in the Deep","artist":"Adele","album":"21","uri":"NAS/共享文件/音乐/@Apple Music Top100/[015]Adele - 21 (2011) [ALAC 16B-44.1kHz]/01 Rolling in the Deep.m4a","trackType":"m4a"} Oct 26 14:41:16 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::pushState Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 26 14:41:16 volumio volumio[907]: info: ------------------------------ 143ms Oct 26 14:41:16 volumio volumio[907]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 26 14:41:16 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:41:16 volumio volumio[907]: info: ControllerMpd::pushState Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"960 Kbps","isStreaming":false,"title":"Rolling in the Deep","artist":"Adele","album":"21","uri":"NAS/共享文件/音乐/@Apple Music Top100/[015]Adele - 21 (2011) [ALAC 16B-44.1kHz]/01 Rolling in the Deep.m4a","trackType":"m4a"} Oct 26 14:41:16 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Oct 26 14:41:16 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::pushState Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 26 14:41:16 volumio volumio[907]: info: CoreStateMachine::pushState Oct 26 14:41:16 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 26 14:41:16 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 26 14:41:16 volumio volumio[907]: info: ------------------------------ 132ms Oct 26 14:41:36 volumio volumio[907]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 26 14:41:36 volumio volumio[907]: info: Retrying Login Due to Network Error Oct 26 14:41:46 volumio volumio[907]: info: MyVolumio login type: Token Oct 26 14:42:15 volumio kernel: Under-voltage detected! (0x00050005) Oct 26 14:42:16 volumio volumio[907]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 26 14:42:16 volumio volumio[907]: info: Retrying Login Due to Network Error Oct 26 14:42:21 volumio kernel: Voltage normalised (0x00000000) Oct 26 14:42:25 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:42:25 volumio volumio[907]: Error: connect ETIMEDOUT 159.138.20.20:80 Oct 26 14:42:25 volumio volumio[907]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 26 14:42:25 volumio volumio[907]: errno: -110, Oct 26 14:42:25 volumio volumio[907]: code: 'ETIMEDOUT', Oct 26 14:42:25 volumio volumio[907]: syscall: 'connect', Oct 26 14:42:25 volumio volumio[907]: address: '159.138.20.20', Oct 26 14:42:25 volumio volumio[907]: port: 80 Oct 26 14:42:25 volumio volumio[907]: } Oct 26 14:42:25 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:42:26 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 14:41 Oct 26 14:42:26 volumio sudo[2088]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"