-- Logs begin at Fri 2024-08-30 15:48:52 CST, end at Fri 2024-08-30 16:28:20 CST. -- Aug 30 16:27:03 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:27:03 volumio volumio[729]: info: CURURI: music-library/USB/music/母带类 Aug 30 16:27:04 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:04 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:04 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:04 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:04 volumio volumio[729]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 16:27:04 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 16:27:10 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:27:10 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 16:27:39 volumio ntpd[5299]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.118.1.130 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.118.1.81 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 211.68.71.26 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 119.28.183.184 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 1.117.63.30 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.118.1.130 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.12 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.118.1.81 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.14 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 119.28.183.184 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.14 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.118.1.81 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 202.112.29.82 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.14 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 1.117.63.30 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 193.182.111.143 Aug 30 16:27:53 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 119.28.206.193 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 84.16.67.12 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.143 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 202.118.1.130 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.143 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.14 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 84.16.67.12 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 162.159.200.1 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 78.46.102.180 Aug 30 16:27:54 volumio ntpd[5299]: Soliciting pool server 193.182.111.143 Aug 30 16:27:55 volumio ntpd[5299]: Soliciting pool server 162.159.200.123 Aug 30 16:27:55 volumio ntpd[5299]: Soliciting pool server 193.182.111.14 Aug 30 16:27:55 volumio ntpd[5299]: Soliciting pool server 211.68.71.26 Aug 30 16:27:56 volumio ntpd[5299]: Soliciting pool server 84.16.67.12 Aug 30 16:27:57 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 193.182.111.141 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 95.111.202.5 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:27:59 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 193.182.111.141 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 108.59.2.24 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 211.68.71.118 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 185.209.85.222 Aug 30 16:28:00 volumio ntpd[5299]: Soliciting pool server 193.182.111.142 Aug 30 16:27:12 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:27:12 volumio volumio[729]: info: CURURI: music-library/USB/music/母带类/24bit 舞曲 Aug 30 16:27:17 volumio volumio[729]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 30 16:27:19 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Aug 30 16:27:19 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 16:27:21 volumio volumio[729]: info: Discovery: Getting this device information Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 16:27:21 volumio volumio[729]: verbose: New Socket.io Connection to 192.168.0.101:3000 from 192.168.0.102 UA: Dalvik/2.1.0 (Linux; U; Android 9; INE-AL00 Build/HUAWEIINE-AL00) Total Clients: 6 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetQueue Aug 30 16:27:21 volumio volumio[729]: info: CoreStateMachine::getQueue Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getQueue Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetState Aug 30 16:27:21 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 16:27:21 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 16:27:24 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:27:24 volumio volumio[729]: info: CURURI: music-library/USB/music/古典类 Aug 30 16:27:29 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:27:29 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 16:27:31 volumio volumio-remote-updater[427]: [2024-08-30 16:27:31] [disconnect] Disconnect close local:[1000] remote:[1000] Aug 30 16:27:31 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Aug 30 16:27:32 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 16:27:32 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Aug 30 16:27:32 volumio systemd[1]: Stopped volumio-remote-updater.service. Aug 30 16:27:32 volumio systemd[1]: Started volumio-remote-updater.service. Aug 30 16:27:32 volumio volumio-remote-updater[7371]: Error: No active session Aug 30 16:27:32 volumio volumio-remote-updater[7371]: [2024-08-30 16:27:32] [connect] Successful connection Aug 30 16:27:32 volumio volumio-remote-updater[7371]: [2024-08-30 16:27:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725006452 101 Aug 30 16:27:32 volumio volumio[729]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Aug 30 16:27:37 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:38 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:38 volumio volumio[729]: error: Upnp client error: Error: This socket has been ended by the other party Aug 30 16:27:38 volumio volumio[729]: error: updateQueue error: null Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 2ms Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand status took 2 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::pushState Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::servicePushState Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:38 volumio volumio[729]: 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} Aug 30 16:27:38 volumio volumio[729]: verbose: CURRENT POSITION 0 Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState stateService stop Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::play index undefined Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::clearAddPlayTracks USB/music/人声类/小娟/小娟 細說往事/細說往事/02-Gone_The_Rainbow.flac Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 17ms Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand stop took 6 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:38 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand clear took 6 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand add "USB/music/人声类/小娟/小娟 細說往事/細說往事/02-Gone_The_Rainbow.flac" Aug 30 16:27:38 volumio volumio[729]: error: updateQueue error: null Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:38 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 4ms Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand add "USB/music/人声类/小娟/小娟 細說往事/細說往事/02-Gone_The_Rainbow.flac" took 1 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand play Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 2ms Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand play took 4 milliseconds Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand status took 145 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:27:38 volumio volumio[729]: info: Aug 30 16:27:38 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::pushState Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::servicePushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Gone The Rainbow","artist":"小娟 & 山谷裡的居民","album":"細說往事","uri":"USB/music/人声类/小娟/小娟 細說往事/細說往事/02-Gone_The_Rainbow.flac","trackType":"flac"} Aug 30 16:27:38 volumio volumio[729]: verbose: CURRENT POSITION 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState stateService play Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState currentStatus stop Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 148ms Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand status took 2 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:27:38 volumio volumio[729]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 30 16:27:38 volumio volumio[729]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:27:38 volumio volumio[729]: info: ControllerMpd::pushState Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::servicePushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Gone The Rainbow","artist":"小娟 & 山谷裡的居民","album":"細說往事","uri":"USB/music/人声类/小娟/小娟 細說往事/細說往事/02-Gone_The_Rainbow.flac","trackType":"flac"} Aug 30 16:27:38 volumio volumio[729]: verbose: CURRENT POSITION 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState stateService play Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:27:38 volumio volumio[729]: info: Received an update from plugin. extracting info from payload Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:38 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:38 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:38 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:38 volumio volumio[729]: info: ------------------------------ 27ms Aug 30 16:27:40 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:27:40 volumio volumio[729]: info: CURURI: music-library/USB/music/古典类/RR 贝多芬第五第七交响乐2015[DSD128] Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::ClearQueue Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::stop Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::updateTrackBlock Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrackBlock Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::serviceStop Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrack 1 Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::serviceStop Aug 30 16:27:49 volumio volumio[729]: info: ControllerMpd::stop Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::clearPlayQueue Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::saveQueue Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::addQueueItems Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::addQueueItems Aug 30 16:27:49 volumio volumio[729]: info: Preload queue cleared Aug 30 16:27:49 volumio volumio[729]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119 Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::saveQueue Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::updateTrackBlock Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrackBlock Aug 30 16:27:49 volumio volumio[729]: info: CoreCommandRouter::volumioPlay Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::play index 0 Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::stop Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::play index undefined Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:49 volumio volumio[729]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:27:49 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:49 volumio volumio[729]: info: [1725006469895] ControllerWebradio::clearAddPlayTrack Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:27:49 volumio volumio[729]: info: sendMpdCommand stop took 99 milliseconds Aug 30 16:27:49 volumio volumio[729]: info: Aug 30 16:27:49 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:49 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:49 volumio volumio[729]: info: sendMpdCommand stop took 90 milliseconds Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 16:27:49 volumio volumio[729]: info: Aug 30 16:27:49 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:49 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:49 volumio volumio[729]: info: sendMpdCommand status took 1 milliseconds Aug 30 16:27:49 volumio volumio[729]: info: sendMpdCommand clear took 1 milliseconds Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119" Aug 30 16:27:49 volumio volumio[729]: error: updateQueue error: null Aug 30 16:27:49 volumio volumio[729]: info: ------------------------------ 2ms Aug 30 16:27:49 volumio volumio[729]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 16:27:49 volumio volumio[729]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:27:49 volumio volumio[729]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 16:27:49 volumio volumio[729]: info: ------------------------------ 4ms Aug 30 16:27:51 volumio volumio[729]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598119" took 1581 milliseconds Aug 30 16:27:51 volumio volumio[729]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:27:51 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand play Aug 30 16:27:51 volumio volumio[729]: info: Aug 30 16:27:51 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:51 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:51 volumio volumio[729]: info: sendMpdCommand play took 5 milliseconds Aug 30 16:27:51 volumio volumio[729]: info: ------------------------------ 5ms Aug 30 16:27:52 volumio volumio[729]: info: Aug 30 16:27:52 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:52 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:52 volumio volumio[729]: info: sendMpdCommand status took 147 milliseconds Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:27:52 volumio volumio[729]: info: Aug 30 16:27:52 volumio volumio[729]: ---------------------------- MPD announces state update: player Aug 30 16:27:52 volumio volumio[729]: info: ControllerMpd::getState Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:27:52 volumio volumio[729]: info: Aug 30 16:27:52 volumio volumio[729]: ---------------------------- MPD announces system playlist update Aug 30 16:27:52 volumio volumio[729]: info: Ignoring MPD Status Update Aug 30 16:27:52 volumio volumio[729]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 16:27:52 volumio volumio[729]: info: sendMpdCommand status took 1 milliseconds Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::parseState Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:27:52 volumio volumio[729]: info: ControllerMpd::pushState Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::servicePushState Aug 30 16:27:52 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:52 volumio volumio[729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Francesco Maria Veracini (1690-1768) - Sonata in la Maggiore No.7 Op.1 (11:12) (Palladian Ensemble) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Aug 30 16:27:52 volumio volumio[729]: verbose: CURRENT POSITION 0 Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::syncState stateService play Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::syncState currentStatus stop Aug 30 16:27:52 volumio volumio[729]: info: ------------------------------ 150ms Aug 30 16:27:52 volumio volumio[729]: info: ------------------------------ 2ms Aug 30 16:27:52 volumio volumio[729]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 16:27:52 volumio volumio[729]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:27:52 volumio volumio[729]: info: ControllerMpd::pushState Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::servicePushState Aug 30 16:27:52 volumio volumio[729]: info: CorePlayQueue::getTrack 0 Aug 30 16:27:52 volumio volumio[729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"123 Kbps","isStreaming":false,"title":"Francesco Maria Veracini (1690-1768) - Sonata in la Maggiore No.7 Op.1 (11:12) (Palladian Ensemble) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} Aug 30 16:27:52 volumio volumio[729]: verbose: CURRENT POSITION 0 Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::syncState stateService play Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:27:52 volumio volumio[729]: info: Received an update from plugin. extracting info from payload Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:52 volumio volumio[729]: info: CoreStateMachine::pushState Aug 30 16:27:52 volumio volumio[729]: info: CoreCommandRouter::volumioPushState Aug 30 16:27:52 volumio volumio[729]: info: ------------------------------ 14ms Aug 30 16:27:56 volumio ntpd[5299]: Soliciting pool server 84.16.73.33 Aug 30 16:28:04 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:28:08 volumio volumio[729]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 16:28:14 volumio volumio[729]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 30 16:28:14 volumio volumio[729]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 30 16:28:20 volumio volumio[729]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:28:20 volumio volumio[729]: Error: connect ETIMEDOUT 199.16.156.75:80 Aug 30 16:28:20 volumio volumio[729]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 30 16:28:20 volumio volumio[729]: errno: -110, Aug 30 16:28:20 volumio volumio[729]: code: 'ETIMEDOUT', Aug 30 16:28:20 volumio volumio[729]: syscall: 'connect', Aug 30 16:28:20 volumio volumio[729]: address: '199.16.156.75', Aug 30 16:28:20 volumio volumio[729]: port: 80 Aug 30 16:28:20 volumio volumio[729]: } Aug 30 16:28:20 volumio volumio[729]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:28:20 volumio sudo[7453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 16:27 Aug 30 16:28:20 volumio sudo[7453]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 06:04:32 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd58c1c2d5a97e584de8089531ce548f"