-- Logs begin at Sun 2024-04-28 13:49:12 CST, end at Sun 2024-04-28 13:56:20 CST. -- Apr 28 13:55:02 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:02 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:04 volumio volumio[613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%85%8B%E5%8B%A4/Purple%20Dream/1483e597-1beb-48d8-928e-d3a69b69ec77.jpg' Apr 28 13:55:10 volumio volumio[613]: info: Apr 28 13:55:10 volumio volumio[613]: ---------------------------- Client requests Volumio pause Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::volumioPause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::stPlaybackTimer Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::servicePause Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::servicePause Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::pause Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand pause Apr 28 13:55:10 volumio volumio[613]: info: Apr 28 13:55:10 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand pause took 3 milliseconds Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:10 volumio volumio[613]: info: Apr 28 13:55:10 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:10 volumio volumio[613]: info: Apr 28 13:55:10 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand status took 12 milliseconds Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand status took 2 milliseconds Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand status took 1 milliseconds Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":67206,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"922 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:10 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState stateService pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::stPlaybackTimer Apr 28 13:55:10 volumio volumio[613]: info: ------------------------------ 20ms Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 28 13:55:10 volumio volumio[613]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:10 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":67206,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"922 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:10 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState stateService pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::stPlaybackTimer Apr 28 13:55:10 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":67206,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"922 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:10 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState stateService pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus pause Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:10 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:10 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:10 volumio volumio[613]: info: CoreStateMachine::stPlaybackTimer Apr 28 13:55:10 volumio volumio[613]: info: ------------------------------ 22ms Apr 28 13:55:10 volumio volumio[613]: info: ------------------------------ 21ms Apr 28 13:55:10 volumio volumio[613]: info: Listing playlists Apr 28 13:55:10 volumio volumio[613]: info: Listing playlists Apr 28 13:55:11 volumio volumio[613]: info: Apr 28 13:55:11 volumio volumio[613]: ---------------------------- Client requests Volumio play Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPlay Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::play index undefined Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::startPlaybackTimer Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::resume Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand play Apr 28 13:55:11 volumio volumio[613]: info: Apr 28 13:55:11 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand play took 1 milliseconds Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:11 volumio volumio[613]: info: Apr 28 13:55:11 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:11 volumio volumio[613]: info: Apr 28 13:55:11 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand status took 4 milliseconds Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand status took 4 milliseconds Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand status took 4 milliseconds Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 28 13:55:11 volumio volumio[613]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:11 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67935,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"663 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:11 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus pause Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67935,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"663 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:11 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:55:11 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:11 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67935,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"663 Kbps","isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:55:11 volumio volumio[613]: verbose: CURRENT POSITION 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:55:11 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:11 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:55:11 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:11 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:55:11 volumio volumio[613]: info: ------------------------------ 36ms Apr 28 13:55:11 volumio volumio[613]: info: ------------------------------ 35ms Apr 28 13:55:11 volumio volumio[613]: info: ------------------------------ 35ms Apr 28 13:55:12 volumio volumio[613]: info: Apr 28 13:55:12 volumio volumio[613]: ---------------------------- Client requests Volumio play Apr 28 13:55:12 volumio volumio[613]: info: CoreCommandRouter::volumioPlay Apr 28 13:55:12 volumio volumio[613]: info: CoreStateMachine::play index undefined Apr 28 13:55:12 volumio volumio[613]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 13:55:12 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:12 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:12 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:12 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:12 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:22 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:22 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:32 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:32 volumio volumio[613]: info: Listing playlists Apr 28 13:55:32 volumio volumio[613]: info: Listing playlists Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 28 13:55:32 volumio volumio-remote-updater[468]: No test mode Apr 28 13:55:32 volumio volumio-remote-updater[468]: No alpha test mode Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 28 13:55:32 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 28 13:55:34 volumio volumio[613]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 28 13:55:34 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 28 13:55:42 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:42 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:50 volumio ntpd[971]: Soliciting pool server 193.182.111.142 Apr 28 13:55:52 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:55:52 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:55:52 volumio volumio[613]: info: Listing playlists Apr 28 13:55:52 volumio volumio[613]: info: Listing playlists Apr 28 13:55:56 volumio ntpd[971]: Soliciting pool server 2001:41d0:305:2100::3f3e Apr 28 13:56:00 volumio ntpd[971]: Soliciting pool server 78.46.102.180 Apr 28 13:56:02 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:56:02 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:56:03 volumio ntpd[971]: Soliciting pool server 162.159.200.1 Apr 28 13:56:12 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:56:12 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:56:12 volumio volumio[613]: info: Listing playlists Apr 28 13:56:12 volumio volumio[613]: info: Listing playlists Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- Client requests Volumio next Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioNext Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::next Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::stop Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::stPlaybackTimer Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::updateTrackBlock Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrackBlock Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::serviceStop Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 0 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::serviceStop Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::stop Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand stop took 5 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::play index undefined Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::startPlaybackTimer Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/淑熙 - 라라라 (啦啦啦).flac Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::updateTrackBlock Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrackBlock Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 7 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand stop took 7 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 2 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand clear Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand clear took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/淑熙 - 라라라 (啦啦啦).flac" Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一生不变","artist":"李克勤","album":"Purple Dream","uri":"INTERNAL/李克勤 - 一生不变.flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService stop Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus stop Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: No code Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 28 13:56:15 volumio volumio[613]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 23ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 18ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 17ms Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: error: updateQueue error: null Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 14ms Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand add "INTERNAL/淑熙 - 라라라 (啦啦啦).flac" took 12 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 2ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 2ms Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand play Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces system playlist update Apr 28 13:56:15 volumio volumio[613]: info: Ignoring MPD Status Update Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 17ms Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand play took 16 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 13ms Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 11ms Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 3 milliseconds Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 2 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: info: Apr 28 13:56:15 volumio volumio[613]: ---------------------------- MPD announces state update: player Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::getState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand status Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"라라라","artist":"숙희","album":"The First Experience","uri":"INTERNAL/淑熙 - 라라라 (啦啦啦).flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus stop Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"라라라","artist":"숙희","album":"The First Experience","uri":"INTERNAL/淑熙 - 라라라 (啦啦啦).flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:56:15 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"라라라","artist":"숙희","album":"The First Experience","uri":"INTERNAL/淑熙 - 라라라 (啦啦啦).flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:56:15 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"라라라","artist":"숙희","album":"The First Experience","uri":"INTERNAL/淑熙 - 라라라 (啦啦啦).flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:56:15 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 20ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 31ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 30ms Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 29ms Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand status took 27 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseState Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 13:56:15 volumio volumio[613]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 28 13:56:15 volumio volumio[613]: verbose: ControllerMpd::parseTrackInfo Apr 28 13:56:15 volumio volumio[613]: info: ControllerMpd::pushState Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::servicePushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"라라라","artist":"숙희","album":"The First Experience","uri":"INTERNAL/淑熙 - 라라라 (啦啦啦).flac","trackType":"flac"} Apr 28 13:56:15 volumio volumio[613]: verbose: CURRENT POSITION 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState stateService play Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::syncState currentStatus play Apr 28 13:56:15 volumio volumio[613]: info: Received an update from plugin. extracting info from payload Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: CoreStateMachine::pushState Apr 28 13:56:15 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:15 volumio volumio[613]: info: CoreCommandRouter::volumioPushState Apr 28 13:56:15 volumio volumio[613]: info: ------------------------------ 45ms Apr 28 13:56:16 volumio volumio[613]: info: Apr 28 13:56:16 volumio volumio[613]: ---------------------------- Client requests Volumio play Apr 28 13:56:16 volumio volumio[613]: info: CoreCommandRouter::volumioPlay Apr 28 13:56:16 volumio volumio[613]: info: CoreStateMachine::play index undefined Apr 28 13:56:16 volumio volumio[613]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 13:56:16 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:16 volumio volumio[613]: info: CoreCommandRouter::volumioGetState Apr 28 13:56:16 volumio volumio[613]: info: CorePlayQueue::getTrack 1 Apr 28 13:56:20 volumio volumio[613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 13:56:20 volumio volumio[613]: Error: connect ETIMEDOUT 199.59.148.247:443 Apr 28 13:56:20 volumio volumio[613]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 28 13:56:20 volumio volumio[613]: errno: -110, Apr 28 13:56:20 volumio volumio[613]: code: 'ETIMEDOUT', Apr 28 13:56:20 volumio volumio[613]: syscall: 'connect', Apr 28 13:56:20 volumio volumio[613]: address: '199.59.148.247', Apr 28 13:56:20 volumio volumio[613]: port: 443 Apr 28 13:56:20 volumio volumio[613]: } Apr 28 13:56:20 volumio volumio[613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 13:56:20 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 13:55 Apr 28 13:56:20 volumio sudo[1889]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"