-- Logs begin at Sat 2024-04-27 08:30:31 UTC, end at Sat 2024-04-27 10:45:45 UTC. -- Apr 27 10:44:23 volumio dhcpcd[1014]: eth0: adding route to 192.168.3.0/24 Apr 27 10:44:23 volumio dhcpcd[1014]: eth0: adding default route via 192.168.3.100 Apr 27 10:44:23 volumio dhcpcd[1014]: eth0: ipv4_addroute: File exists Apr 27 10:44:23 volumio dhcpcd[1014]: eth0: removing route to 192.168.3.0/24 Apr 27 10:44:53 volumio dhcpcd[1014]: eth0: adding route to 192.168.3.0/24 Apr 27 10:44:53 volumio dhcpcd[1014]: eth0: adding default route via 192.168.3.100 Apr 27 10:44:53 volumio dhcpcd[1014]: eth0: ipv4_addroute: File exists Apr 27 10:44:53 volumio dhcpcd[1014]: eth0: removing route to 192.168.3.0/24 Apr 27 10:45:06 volumio volumio[26934]: info: Apr 27 10:45:06 volumio volumio[26934]: ---------------------------- MPD announces state update: player Apr 27 10:45:06 volumio volumio[26934]: info: ControllerMpd::getState Apr 27 10:45:06 volumio volumio[26934]: verbose: ControllerMpd::sendMpdCommand status Apr 27 10:45:06 volumio volumio[26934]: verbose: ControllerMpd::parseState Apr 27 10:45:06 volumio volumio[26934]: info: ControllerMpd::pushState Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::servicePushState Apr 27 10:45:06 volumio volumio[26934]: info: CoreStateMachine::pushState Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:06 volumio volumio[26934]: info: CorePlayQueue::getTrack 0 Apr 27 10:45:06 volumio volumio[26934]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 27 10:45:06 volumio volumio[26934]: verbose: CURRENT POSITION 0 Apr 27 10:45:06 volumio volumio[26934]: info: CoreStateMachine::syncState stateService stop Apr 27 10:45:06 volumio volumio[26934]: info: CoreStateMachine::syncState currentStatus play Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:06 volumio volumio[26934]: info: CoreStateMachine::stPlaybackTimer Apr 27 10:45:06 volumio volumio[26934]: info: ------------------------------ 18ms Apr 27 10:45:06 volumio volumio[26934]: info: CoreCommandRouter::volumioGetQueue Apr 27 10:45:06 volumio volumio[26934]: info: CoreStateMachine::getQueue Apr 27 10:45:06 volumio volumio[26934]: info: CorePlayQueue::getQueue Apr 27 10:45:06 volumio volumio[26934]: info: Clearing queue after UPNP request Apr 27 10:45:07 volumio volumio[26934]: info: CoreStateMachine::ClearQueue Apr 27 10:45:07 volumio volumio[26934]: info: CoreStateMachine::stop Apr 27 10:45:07 volumio volumio[26934]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 10:45:07 volumio volumio[26934]: UNSET VOLATILE Apr 27 10:45:07 volumio volumio[26934]: info: CorePlayQueue::clearPlayQueue Apr 27 10:45:07 volumio volumio[26934]: info: CorePlayQueue::saveQueue Apr 27 10:45:07 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:07 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:07 volumio volumio[26934]: info: CoreCommandRouter::volumioPushQueue Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: error: updateQueue error: null Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 2ms Apr 27 10:45:07 volumio volumio[26934]: info: Starting UPNP Playback Apr 27 10:45:07 volumio volumio[26934]: info: Preparing playback through UPNP Apr 27 10:45:07 volumio volumio[26934]: info: CoreCommandRouter::volumioGetState Apr 27 10:45:07 volumio volumio[26934]: info: CorePlayQueue::getTrack 0 Apr 27 10:45:07 volumio volumio[26934]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 4ms Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 2ms Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 1ms Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 2ms Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 2ms Apr 27 10:45:07 volumio volumio[26934]: info: Apr 27 10:45:07 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:07 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:07 volumio volumio[26934]: info: ------------------------------ 1ms Apr 27 10:45:08 volumio volumio[26934]: info: Apr 27 10:45:08 volumio volumio[26934]: ---------------------------- MPD announces state update: player Apr 27 10:45:08 volumio volumio[26934]: info: ControllerMpd::getState Apr 27 10:45:08 volumio volumio[26934]: verbose: ControllerMpd::sendMpdCommand status Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::parseState Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 10:45:09 volumio volumio[26934]: info: Apr 27 10:45:09 volumio volumio[26934]: ---------------------------- MPD announces state update: player Apr 27 10:45:09 volumio volumio[26934]: info: ControllerMpd::getState Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::sendMpdCommand status Apr 27 10:45:09 volumio volumio[26934]: info: Apr 27 10:45:09 volumio volumio[26934]: ---------------------------- MPD announces system playlist update Apr 27 10:45:09 volumio volumio[26934]: info: Ignoring MPD Status Update Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::parseTrackInfo Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::parseState Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 10:45:09 volumio volumio[26934]: info: ControllerMpd::pushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::servicePushState Apr 27 10:45:09 volumio volumio[26934]: In UPNP mode Apr 27 10:45:09 volumio volumio[26934]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Let's Do It, Let's Fall in Love","artist":"Ella Fitzgerald","album":"Dynamic Divas of Jazz - Ella Fitzgerald","uri":"http://120.234.70.13/amobile.music.tc.qq.com/M500001sracz0CDvtX.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=29D2A18DE17E13E0EA1FD060E00FBFAF629DEC0AE7D670E60E46DA189A88BCB4D8734849380A50149E1FA4826E50D881D8A7BE2C94EC1C3C&uin=1259438298&redirect=1&fromtag=111042","trackType":""} Apr 27 10:45:09 volumio volumio[26934]: verbose: CURRENT POSITION 0 Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::syncState stateService play Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::syncState currentStatus stop Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::pushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:09 volumio volumio[26934]: info: ------------------------------ 956ms Apr 27 10:45:09 volumio volumio[26934]: info: ------------------------------ 20ms Apr 27 10:45:09 volumio volumio[26934]: verbose: ControllerMpd::parseTrackInfo Apr 27 10:45:09 volumio volumio[26934]: info: ControllerMpd::pushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::servicePushState Apr 27 10:45:09 volumio volumio[26934]: In UPNP mode Apr 27 10:45:09 volumio volumio[26934]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Let's Do It, Let's Fall in Love","artist":"Ella Fitzgerald","album":"Dynamic Divas of Jazz - Ella Fitzgerald","uri":"http://120.234.70.13/amobile.music.tc.qq.com/M500001sracz0CDvtX.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=29D2A18DE17E13E0EA1FD060E00FBFAF629DEC0AE7D670E60E46DA189A88BCB4D8734849380A50149E1FA4826E50D881D8A7BE2C94EC1C3C&uin=1259438298&redirect=1&fromtag=111042","trackType":""} Apr 27 10:45:09 volumio volumio[26934]: verbose: CURRENT POSITION 0 Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::syncState stateService play Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::syncState currentStatus play Apr 27 10:45:09 volumio volumio[26934]: info: Received an update from plugin. extracting info from payload Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::pushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::pushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::volumioPushState Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 10:45:09 volumio volumio[26934]: info: ------------------------------ 50ms Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::volumioGetQueue Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::getQueue Apr 27 10:45:09 volumio volumio[26934]: info: CorePlayQueue::getQueue Apr 27 10:45:09 volumio volumio[26934]: info: CoreCommandRouter::volumioGetQueue Apr 27 10:45:09 volumio volumio[26934]: info: CoreStateMachine::getQueue Apr 27 10:45:09 volumio volumio[26934]: info: CorePlayQueue::getQueue Apr 27 10:45:09 volumio volumio[26934]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ella%20Fitzgerald/Ella%20Fitzgerald%20Sings%20The%20Cole%20Porter%20Song%20Book/e945ae5d-d567-41d3-888f-3275b33268ed.jpg' Apr 27 10:45:23 volumio dhcpcd[1014]: eth0: adding route to 192.168.3.0/24 Apr 27 10:45:23 volumio dhcpcd[1014]: eth0: adding default route via 192.168.3.100 Apr 27 10:45:23 volumio dhcpcd[1014]: eth0: ipv4_addroute: File exists Apr 27 10:45:23 volumio dhcpcd[1014]: eth0: removing route to 192.168.3.0/24 Apr 27 10:45:45 volumio volumio[26934]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 10:45:45 volumio volumio[26934]: { Error: connect ETIMEDOUT 157.240.12.36:443 Apr 27 10:45:45 volumio volumio[26934]: at Object._errnoException (util.js:1022:11) Apr 27 10:45:45 volumio volumio[26934]: at _exceptionWithHostPort (util.js:1044:20) Apr 27 10:45:45 volumio volumio[26934]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 27 10:45:45 volumio volumio[26934]: code: 'ETIMEDOUT', Apr 27 10:45:45 volumio volumio[26934]: errno: 'ETIMEDOUT', Apr 27 10:45:45 volumio volumio[26934]: syscall: 'connect', Apr 27 10:45:45 volumio volumio[26934]: address: '157.240.12.36', Apr 27 10:45:45 volumio volumio[26934]: port: 443 } Apr 27 10:45:45 volumio volumio[26934]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 10:45:45 volumio sudo[28062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 10:44 Apr 27 10:45:45 volumio sudo[28062]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 15:00:46 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="b3401e25b8d19b16b1038c2843d2deaf"