-- Logs begin at Thu 2024-11-21 14:59:56 UTC, end at Thu 2024-11-21 15:37:59 UTC. -- Nov 21 15:37:02 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:37:02 volumio volumio[8123]: info: CURURI: music-library Nov 21 15:37:02 volumio volumio[8123]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:37:02 volumio volumio[8123]: info: CoreStateMachine::getQueue Nov 21 15:37:02 volumio volumio[8123]: info: CorePlayQueue::getQueue Nov 21 15:37:03 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:37:03 volumio volumio[8123]: info: CURURI: music-library/NAS Nov 21 15:37:04 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:37:04 volumio volumio[8123]: info: CURURI: music-library/NAS/NAS Nov 21 15:37:05 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:37:05 volumio volumio[8123]: info: CURURI: music-library/NAS/NAS/缓存 Nov 21 15:37:09 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 15:37:15 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:16 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:17 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:19 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:20 volumio volumio[8123]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BLU-SWING/794c1fe5-d59b-4c1f-a5a8-067e5d25e949.jpg' Nov 21 15:37:20 volumio volumio[8123]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Awesome%20City%20Club/38eaead4-7d43-40d4-afca-9db27554c6a1.jpg' Nov 21 15:37:25 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:37 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:38 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:37:39 volumio volumio[8123]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aimer/BEST%20SELECTION%20%22blanc%22/aab1613a-b66e-45ff-a73f-b49e90e7d6cd.jpg' Nov 21 15:37:43 volumio volumio[8123]: info: CoreCommandRouter::volumioAddQueueItems Nov 21 15:37:43 volumio volumio[8123]: info: CoreStateMachine::addQueueItems Nov 21 15:37:43 volumio volumio[8123]: info: CorePlayQueue::addQueueItems Nov 21 15:37:43 volumio volumio[8123]: info: Adding Item to queue: upnp/folder/http://192.168.31.1:8200/ctl/ContentDir@1$14$6 Nov 21 15:37:43 volumio volumio[8123]: info: Exploding uri upnp/folder/http://192.168.31.1:8200/ctl/ContentDir@1$14$6 in service upnp_browser Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::saveQueue Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::volumioPushQueue Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::updateTrackBlock Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrackBlock Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::volumioPlay Nov 21 15:37:44 volumio volumio[8123]: verbose: UNSET VOLATILE Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::play index 60 Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::stop Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:37:44 volumio volumio[8123]: verbose: UNSET VOLATILE Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::stPlaybackTimer Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::updateTrackBlock Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrackBlock Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::pushState Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrack 13 Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::volumioPushState Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::serviceStop Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrack 13 Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::serviceStop Nov 21 15:37:44 volumio volumio[8123]: info: ControllerMpd::stop Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:37:44 volumio volumio[8123]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:37:44 volumio volumio[8123]: info: CoreCommandRouter::volumioGetState Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrack 13 Nov 21 15:37:44 volumio volumio[8123]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:37:44 volumio volumio[8123]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand stop took 219 milliseconds Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::play index undefined Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrack 60 Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:37:44 volumio volumio[8123]: info: CorePlayQueue::getTrack 60 Nov 21 15:37:44 volumio volumio[8123]: info: [1732203464593] ControllerUPNPBrowser::clearAddPlayTrack Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:37:44 volumio volumio[8123]: info: Nov 21 15:37:44 volumio volumio[8123]: ---------------------------- MPD announces state update: player Nov 21 15:37:44 volumio volumio[8123]: info: ControllerMpd::getState Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand stop took 4 milliseconds Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:37:44 volumio volumio[8123]: info: Nov 21 15:37:44 volumio volumio[8123]: ---------------------------- MPD announces system playlist update Nov 21 15:37:44 volumio volumio[8123]: info: Ignoring MPD Status Update Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand status took 7 milliseconds Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand clear took 6 milliseconds Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::parseState Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.1:8200/MediaItems/56.flac" Nov 21 15:37:44 volumio volumio[8123]: error: updateQueue error: null Nov 21 15:37:44 volumio volumio[8123]: info: ------------------------------ 7ms Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:37:44 volumio volumio[8123]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 15:37:44 volumio volumio[8123]: info: ------------------------------ 16ms Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.1:8200/MediaItems/56.flac" Nov 21 15:37:44 volumio volumio[8123]: info: Nov 21 15:37:44 volumio volumio[8123]: ---------------------------- MPD announces system playlist update Nov 21 15:37:44 volumio volumio[8123]: info: Ignoring MPD Status Update Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand add "http://192.168.31.1:8200/MediaItems/56.flac" took 3 milliseconds Nov 21 15:37:44 volumio volumio[8123]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:37:44 volumio volumio[8123]: info: ------------------------------ 4ms Nov 21 15:37:44 volumio volumio[8123]: info: sendMpdCommand play took 3 milliseconds Nov 21 15:37:44 volumio volumio[8123]: info: Nov 21 15:37:44 volumio volumio[8123]: ---------------------------- MPD announces state update: player Nov 21 15:37:44 volumio volumio[8123]: info: ControllerMpd::getState Nov 21 15:37:44 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:37:45 volumio volumio[8123]: info: sendMpdCommand status took 944 milliseconds Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::parseState Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:37:45 volumio volumio[8123]: info: Nov 21 15:37:45 volumio volumio[8123]: ---------------------------- MPD announces state update: player Nov 21 15:37:45 volumio volumio[8123]: info: ControllerMpd::getState Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:37:45 volumio volumio[8123]: info: Nov 21 15:37:45 volumio volumio[8123]: ---------------------------- MPD announces system playlist update Nov 21 15:37:45 volumio volumio[8123]: info: Ignoring MPD Status Update Nov 21 15:37:45 volumio volumio[8123]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:37:45 volumio volumio[8123]: info: sendMpdCommand status took 2 milliseconds Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::parseState Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:37:45 volumio volumio[8123]: info: ControllerMpd::pushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::servicePushState Nov 21 15:37:45 volumio volumio[8123]: info: CorePlayQueue::getTrack 60 Nov 21 15:37:45 volumio volumio[8123]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝福","artist":"YOASOBI","album":"祝福","uri":"http://192.168.31.1:8200/MediaItems/56.flac","trackType":"flac"} Nov 21 15:37:45 volumio volumio[8123]: verbose: CURRENT POSITION 60 Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::syncState stateService play Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::pushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::volumioPushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:37:45 volumio volumio[8123]: info: ------------------------------ 960ms Nov 21 15:37:45 volumio volumio[8123]: info: ------------------------------ 14ms Nov 21 15:37:45 volumio volumio[8123]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:37:45 volumio volumio[8123]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:37:45 volumio volumio[8123]: info: ControllerMpd::pushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::servicePushState Nov 21 15:37:45 volumio volumio[8123]: info: CorePlayQueue::getTrack 60 Nov 21 15:37:45 volumio volumio[8123]: verbose: STATE SERVICE {"status":"play","position":0,"seek":469,"duration":196,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1044 Kbps","isStreaming":false,"title":"祝福","artist":"YOASOBI","album":"祝福","uri":"http://192.168.31.1:8200/MediaItems/56.flac","trackType":"flac"} Nov 21 15:37:45 volumio volumio[8123]: verbose: CURRENT POSITION 60 Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::syncState stateService play Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:37:45 volumio volumio[8123]: info: Received an update from plugin. extracting info from payload Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::pushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::volumioPushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::pushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::volumioPushState Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:37:45 volumio volumio[8123]: info: ------------------------------ 38ms Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::getQueue Nov 21 15:37:45 volumio volumio[8123]: info: CorePlayQueue::getQueue Nov 21 15:37:45 volumio volumio[8123]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:37:45 volumio volumio[8123]: info: CoreStateMachine::getQueue Nov 21 15:37:45 volumio volumio[8123]: info: CorePlayQueue::getQueue Nov 21 15:37:45 volumio volumio[8123]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:37:59 volumio volumio[8123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:37:59 volumio volumio[8123]: { Error: connect ETIMEDOUT 103.73.161.52:443 Nov 21 15:37:59 volumio volumio[8123]: at Object._errnoException (util.js:1022:11) Nov 21 15:37:59 volumio volumio[8123]: at _exceptionWithHostPort (util.js:1044:20) Nov 21 15:37:59 volumio volumio[8123]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 21 15:37:59 volumio volumio[8123]: code: 'ETIMEDOUT', Nov 21 15:37:59 volumio volumio[8123]: errno: 'ETIMEDOUT', Nov 21 15:37:59 volumio volumio[8123]: syscall: 'connect', Nov 21 15:37:59 volumio volumio[8123]: address: '103.73.161.52', Nov 21 15:37:59 volumio volumio[8123]: port: 443 } Nov 21 15:37:59 volumio volumio[8123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:37:59 volumio sudo[9719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 15:36 Nov 21 15:37:59 volumio sudo[9719]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"