-- Logs begin at Sat 2024-08-17 11:57:36 CST, end at Sat 2024-08-17 12:24:27 CST. -- Aug 17 12:23:00 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 17 12:23:00 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 12:23:00 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 17 12:23:02 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 12:23:02 volumio volumio[4743]: info: CURURI: artists://%E5%A4%8F%E5%A4%A9Alex Aug 17 12:23:02 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:23:02 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:23:02 volumio volumio[4743]: info: Preloading song: music-library/NAS/nas/夏天Alex - 狠角色.flac Aug 17 12:23:02 volumio volumio[4743]: info: Exploding uri music-library/NAS/nas/夏天Alex - 狠角色.flac in service mpd Aug 17 12:23:02 volumio volumio[4743]: info: ALBUMART /albumart?cacheid=992&web=%E5%A4%8F%E5%A4%A9Alex/%E7%AD%89%E6%88%91%E5%85%88%E8%AF%B4/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F%E5%A4%8F%E5%A4%A9Alex%20-%20%E7%8B%A0%E8%A7%92%E8%89%B2.flac&metadata=false Aug 17 12:23:02 volumio volumio[4743]: info: URI /mnt/NAS/nas/夏天Alex - 狠角色.flac Aug 17 12:23:03 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:23:03 volumio volumio[4743]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::ClearQueue Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::stop Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::stPlaybackTimer Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::updateTrackBlock Aug 17 12:23:03 volumio volumio[4743]: info: CorePlayQueue::getTrackBlock Aug 17 12:23:03 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:03 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:03 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:23:03 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::serviceStop Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::serviceStop Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::stop Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::clearPlayQueue Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::saveQueue Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushQueue Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::addQueueItems Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::addQueueItems Aug 17 12:23:04 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:23:04 volumio volumio[4743]: info: Adding Item to queue: music-library/NAS/nas/夏天Alex - 狠角色.flac Aug 17 12:23:04 volumio volumio[4743]: info: Using cached record of: music-library/NAS/nas/夏天Alex - 狠角色.flac Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushQueue Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::saveQueue Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::updateTrackBlock Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrackBlock Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPlay Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::play index 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::stop Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::play index undefined Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::startPlaybackTimer Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/夏天Alex - 狠角色.flac Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand stop took 39 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand stop took 6 milliseconds Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 7 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand clear took 6 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 6 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 4 milliseconds Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/夏天Alex - 狠角色.flac" Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: 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 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService stop Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus stop Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: No code Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: 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 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService stop Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus stop Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: No code Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 70ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 69ms Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: error: updateQueue error: null Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 72ms Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 70 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand add "NAS/nas/夏天Alex - 狠角色.flac" took 69 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 6ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 5ms Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand play Aug 17 12:23:04 volumio volumio[4743]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 84ms Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces system playlist update Aug 17 12:23:04 volumio volumio[4743]: info: Ignoring MPD Status Update Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 25ms Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand play took 22 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 20ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 19ms Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 183 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 180 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 178 milliseconds Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: Aug 17 12:23:04 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 18 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 12 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand status took 10 milliseconds Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus stop Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:23:04 volumio volumio[4743]: info: Received an update from plugin. extracting info from payload Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:23:04 volumio volumio[4743]: info: Received an update from plugin. extracting info from payload Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 234ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 250ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 248ms Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 50 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 17 12:23:04 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:23:04 volumio volumio[4743]: info: Received an update from plugin. extracting info from payload Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:23:04 volumio volumio[4743]: info: Received an update from plugin. extracting info from payload Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"936 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:23:04 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState stateService play Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus play Aug 17 12:23:04 volumio volumio[4743]: info: Received an update from plugin. extracting info from payload Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:04 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:04 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 141ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 133ms Aug 17 12:23:04 volumio volumio[4743]: info: ------------------------------ 130ms Aug 17 12:23:10 volumio volumio[4743]: info: VolumeController::SetAlsaVolume88 Aug 17 12:23:10 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:23:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:10 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:23:10 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:23:16 volumio volumio[4743]: info: CoreCommandRouter::volumioGetState Aug 17 12:23:16 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:23:27 volumio nmbd[892]: [2024/08/17 12:23:27.451593, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 17 12:23:27 volumio nmbd[892]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.6.144 for name WORKGROUP<1d>. Aug 17 12:23:27 volumio nmbd[892]: This response was from IP 192.168.6.233, reporting an IP address of 192.168.6.233. Aug 17 12:23:42 volumio volumio[4743]: info: CoreCommandRouter::volumioGetState Aug 17 12:23:42 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::volumioPause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::stPlaybackTimer Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::servicePause Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::servicePause Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::pause Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand pause Aug 17 12:24:10 volumio volumio[4743]: info: Aug 17 12:24:10 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand pause took 6 milliseconds Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:24:10 volumio volumio[4743]: info: Aug 17 12:24:10 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:24:10 volumio volumio[4743]: info: Aug 17 12:24:10 volumio volumio[4743]: ---------------------------- MPD announces state update: player Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::getState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand status Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand status took 6 milliseconds Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand status took 4 milliseconds Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand status took 5 milliseconds Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseState Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66739,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1840 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:24:10 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState stateService pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::stPlaybackTimer Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66739,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1840 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:24:10 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState stateService pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::stPlaybackTimer Aug 17 12:24:10 volumio volumio[4743]: info: ------------------------------ 39ms Aug 17 12:24:10 volumio volumio[4743]: info: ------------------------------ 38ms Aug 17 12:24:10 volumio volumio[4743]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 17 12:24:10 volumio volumio[4743]: verbose: ControllerMpd::parseTrackInfo Aug 17 12:24:10 volumio volumio[4743]: info: ControllerMpd::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::servicePushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66739,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1840 Kbps","isStreaming":false,"title":"狠角色","artist":"夏天Alex","album":"等我先说","uri":"NAS/nas/夏天Alex - 狠角色.flac","trackType":"flac"} Aug 17 12:24:10 volumio volumio[4743]: verbose: CURRENT POSITION 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState stateService pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::syncState currentStatus pause Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::pushState Aug 17 12:24:10 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:10 volumio volumio[4743]: info: CoreCommandRouter::volumioPushState Aug 17 12:24:10 volumio volumio[4743]: info: CoreStateMachine::stPlaybackTimer Aug 17 12:24:10 volumio volumio[4743]: info: ------------------------------ 53ms Aug 17 12:24:13 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 12:24:13 volumio volumio[4743]: info: CURURI: music-library Aug 17 12:24:13 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:24:17 volumio volumio[4743]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 12:24:17 volumio volumio[4743]: info: CURURI: artists:// Aug 17 12:24:17 volumio volumio[4743]: info: Preload queue cleared Aug 17 12:24:24 volumio volumio[4743]: info: CoreCommandRouter::volumioGetState Aug 17 12:24:24 volumio volumio[4743]: info: CorePlayQueue::getTrack 0 Aug 17 12:24:26 volumio volumio[4743]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 12:24:27 volumio volumio[4743]: Error: Parse Error: Expected HTTP/ Aug 17 12:24:27 volumio volumio[4743]: at Socket.socketOnData (_http_client.js:515:22) Aug 17 12:24:27 volumio volumio[4743]: at Socket.emit (events.js:400:28) Aug 17 12:24:27 volumio volumio[4743]: at addChunk (internal/streams/readable.js:293:12) Aug 17 12:24:27 volumio volumio[4743]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 17 12:24:27 volumio volumio[4743]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 17 12:24:27 volumio volumio[4743]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Aug 17 12:24:27 volumio volumio[4743]: bytesParsed: 0, Aug 17 12:24:27 volumio volumio[4743]: code: 'HPE_INVALID_CONSTANT', Aug 17 12:24:27 volumio volumio[4743]: reason: 'Expected HTTP/', Aug 17 12:24:27 volumio volumio[4743]: rawPacket: Aug 17 12:24:27 volumio volumio[4743]: } Aug 17 12:24:27 volumio volumio[4743]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 12:24:27 volumio sudo[5072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 12:23 Aug 17 12:24:27 volumio sudo[5072]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"