-- Logs begin at Mon 2025-10-13 23:10:26 CST, end at Fri 2025-10-17 01:30:17 CST. -- Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::volumioSeek Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::seek Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: info: TRACKBLOCK {"uri":"mnt/NAS/nas/14-广州6 26/Benny Waters Live at the Pawnshop/01 Out of Nowhere.flac","service":"mpd","name":"Track01","artist":"未知艺术家","album":"未知标题","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=949&web=%E6%9C%AA%E7%9F%A5%E8%89%BA%E6%9C%AF%E5%AE%B6/%E6%9C%AA%E7%9F%A5%E6%A0%87%E9%A2%98/extralarge&path=%2FNAS%2Fnas%2F14-%E5%B9%BF%E5%B7%9E6%2026%2FBenny%20Waters%20Live%20at%20the%20Pawnshop&metadata=false","duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: info: ControllerMpd::seek Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:07 volumio volumio[709]: error: null Oct 17 01:29:07 volumio volumio[709]: info: Oct 17 01:29:07 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:07 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:07 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:07 volumio volumio[709]: info: sendMpdCommand status took 0 milliseconds Oct 17 01:29:07 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:07 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:07 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:07 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:07 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55000,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"Track01","artist":"未知艺术家","album":"未知标题","uri":"NAS/nas/14-广州6 26/Benny Waters Live at the Pawnshop/01 Out of Nowhere.flac","trackType":"flac"} Oct 17 01:29:07 volumio volumio[709]: verbose: CURRENT POSITION 159 Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:07 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:07 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:07 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:07 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:07 volumio volumio[709]: info: ------------------------------ 18ms Oct 17 01:29:13 volumio volumio[709]: info: CoreCommandRouter::volumioPlay Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::play index 169 Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::stop Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::stPlaybackTimer Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::updateTrackBlock Oct 17 01:29:13 volumio volumio[709]: info: CorePlayQueue::getTrackBlock Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:13 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:13 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:13 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:13 volumio volumio[709]: info: CoreStateMachine::serviceStop Oct 17 01:29:13 volumio volumio[709]: info: CorePlayQueue::getTrack 159 Oct 17 01:29:13 volumio volumio[709]: info: CoreCommandRouter::serviceStop Oct 17 01:29:13 volumio volumio[709]: info: ControllerMpd::stop Oct 17 01:29:13 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:14 volumio volumio[709]: info: Oct 17 01:29:14 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand stop took 96 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::play index undefined Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/14-广州6 26/British Folk-Song/01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand status took 4 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand stop took 3 milliseconds Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 01:29:14 volumio volumio[709]: info: Oct 17 01:29:14 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:14 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand clear took 1 milliseconds Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/14-广州6 26/British Folk-Song/01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac" Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: 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":"Track01","artist":"未知艺术家","album":"未知标题","uri":"NAS/nas/14-广州6 26/Benny Waters Live at the Pawnshop/01 Out of Nowhere.flac","trackType":"flac"} Oct 17 01:29:14 volumio volumio[709]: verbose: CURRENT POSITION 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState stateService stop Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus stop Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:14 volumio volumio[709]: info: No code Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:14 volumio volumio[709]: info: ------------------------------ 24ms Oct 17 01:29:14 volumio volumio[709]: error: updateQueue error: null Oct 17 01:29:14 volumio volumio[709]: info: Oct 17 01:29:14 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:14 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:14 volumio volumio[709]: info: ------------------------------ 18ms Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand add "NAS/nas/14-广州6 26/British Folk-Song/01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac" took 17 milliseconds Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play Oct 17 01:29:14 volumio volumio[709]: info: ------------------------------ 6ms Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand play took 6 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: Oct 17 01:29:14 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 17 01:29:14 volumio volumio[709]: info: Oct 17 01:29:14 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand status took 15 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand status took 1 milliseconds Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac","artist":null,"album":null,"uri":"NAS/nas/14-广州6 26/British Folk-Song/01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac","trackType":"flac"} Oct 17 01:29:14 volumio volumio[709]: verbose: CURRENT POSITION 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus stop Oct 17 01:29:14 volumio volumio[709]: info: ------------------------------ 22ms Oct 17 01:29:14 volumio volumio[709]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 01:29:14 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:14 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":246,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"234 Kbps","isStreaming":false,"title":"01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac","artist":null,"album":null,"uri":"NAS/nas/14-广州6 26/British Folk-Song/01 Over the Sea to Skye (a.k.a. ''The Skye Boat Song'').flac","trackType":"flac"} Oct 17 01:29:14 volumio volumio[709]: verbose: CURRENT POSITION 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:14 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:14 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:14 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:14 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:14 volumio volumio[709]: info: ------------------------------ 21ms Oct 17 01:29:16 volumio volumio[709]: info: VolumeController::SetAlsaVolume+ Oct 17 01:29:16 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:16 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:16 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:16 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:16 volumio volumio[709]: info: VolumeController::SetAlsaVolume+ Oct 17 01:29:16 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:16 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:16 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:16 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:28 volumio nmbd[611]: [2025/10/17 01:29:28.318960, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 17 01:29:28 volumio nmbd[611]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.5 for name WORKGROUP<1d>. Oct 17 01:29:28 volumio nmbd[611]: This response was from IP 192.168.10.2, reporting an IP address of 192.168.10.2. Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::volumioPlay Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::play index 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::stop Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::stPlaybackTimer Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::updateTrackBlock Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrackBlock Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::serviceStop Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 169 Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::serviceStop Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::stop Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand stop took 64 milliseconds Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::play index undefined Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/14-广州6 26/Del Los Angeles 1957/01 Il Pirro e Demetrio, opera (''La forza della fedeltà'')- Le violette.flac Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:28 volumio volumio[709]: info: Oct 17 01:29:28 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand stop took 0 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 01:29:28 volumio volumio[709]: info: Oct 17 01:29:28 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:28 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand status took 2 milliseconds Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand clear took 1 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/14-广州6 26/Del Los Angeles 1957/01 Il Pirro e Demetrio, opera (''La forza della fedeltà'')- Le violette.flac" Oct 17 01:29:28 volumio volumio[709]: error: updateQueue error: null Oct 17 01:29:28 volumio volumio[709]: info: Oct 17 01:29:28 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:28 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:28 volumio volumio[709]: info: ------------------------------ 2ms Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand add "NAS/nas/14-广州6 26/Del Los Angeles 1957/01 Il Pirro e Demetrio, opera (''La forza della fedeltà'')- Le violette.flac" took 1 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play Oct 17 01:29:28 volumio volumio[709]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 01:29:28 volumio volumio[709]: info: ------------------------------ 4ms Oct 17 01:29:28 volumio volumio[709]: info: ------------------------------ 2ms Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand play took 1 milliseconds Oct 17 01:29:28 volumio volumio[709]: info: Oct 17 01:29:28 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:28 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 17 01:29:28 volumio volumio[709]: info: Oct 17 01:29:28 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand status took 16 milliseconds Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand status took 1 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Il Pirro e Demetrio, opera ('La forza della fedeltà'): Le violette","artist":"Victoria de Los Angeles","album":"Victoria de los Angeles Performs Scarlatti, Handel, Schubert","uri":"NAS/nas/14-广州6 26/Del Los Angeles 1957/01 Il Pirro e Demetrio, opera (''La forza della fedeltà'')- Le violette.flac","trackType":"flac"} Oct 17 01:29:28 volumio volumio[709]: verbose: CURRENT POSITION 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus stop Oct 17 01:29:28 volumio volumio[709]: info: ------------------------------ 19ms Oct 17 01:29:28 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:28 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:28 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":777,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"283 Kbps","isStreaming":false,"title":"Il Pirro e Demetrio, opera ('La forza della fedeltà'): Le violette","artist":"Victoria de Los Angeles","album":"Victoria de los Angeles Performs Scarlatti, Handel, Schubert","uri":"NAS/nas/14-广州6 26/Del Los Angeles 1957/01 Il Pirro e Demetrio, opera (''La forza della fedeltà'')- Le violette.flac","trackType":"flac"} Oct 17 01:29:28 volumio volumio[709]: verbose: CURRENT POSITION 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:28 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:28 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:28 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:28 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:28 volumio volumio[709]: info: ------------------------------ 17ms Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::volumioPlay Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::play index 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::stop Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::stPlaybackTimer Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::updateTrackBlock Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrackBlock Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::serviceStop Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 223 Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::serviceStop Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::stop Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand stop took 35 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::play index undefined Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:37 volumio volumio[709]: info: Oct 17 01:29:37 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand stop took 0 milliseconds Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 01:29:37 volumio volumio[709]: info: Oct 17 01:29:37 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:37 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand status took 2 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand clear took 1 milliseconds Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac" Oct 17 01:29:37 volumio volumio[709]: error: updateQueue error: null Oct 17 01:29:37 volumio volumio[709]: info: Oct 17 01:29:37 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:37 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:37 volumio volumio[709]: info: ------------------------------ 2ms Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand add "NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac" took 1 milliseconds Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play Oct 17 01:29:37 volumio volumio[709]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 01:29:37 volumio volumio[709]: info: ------------------------------ 4ms Oct 17 01:29:37 volumio volumio[709]: info: ------------------------------ 2ms Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand play took 1 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: Oct 17 01:29:37 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:37 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 17 01:29:37 volumio volumio[709]: info: Oct 17 01:29:37 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand status took 15 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand status took 2 milliseconds Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":942,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dvorak / Cello Concerto - 1. Allegro","artist":"Mstislav Rostropovich","album":"Dvorak Cello Concerto, Tchaikovsky Rokoko Variation","uri":"NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac","trackType":"flac"} Oct 17 01:29:37 volumio volumio[709]: verbose: CURRENT POSITION 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus stop Oct 17 01:29:37 volumio volumio[709]: info: ------------------------------ 20ms Oct 17 01:29:37 volumio volumio[709]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 01:29:37 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:37 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":942,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"399 Kbps","isStreaming":false,"title":"Dvorak / Cello Concerto - 1. Allegro","artist":"Mstislav Rostropovich","album":"Dvorak Cello Concerto, Tchaikovsky Rokoko Variation","uri":"NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac","trackType":"flac"} Oct 17 01:29:37 volumio volumio[709]: verbose: CURRENT POSITION 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:37 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:37 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:37 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:37 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:37 volumio volumio[709]: info: ------------------------------ 22ms Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::volumioSeek Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::seek Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: info: TRACKBLOCK {"uri":"mnt/NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac","service":"mpd","name":"Dvorak / Cello Concerto - 1. Allegro","artist":"Mstislav Rostropovich","album":"Dvorak Cello Concerto, Tchaikovsky Rokoko Variation","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=949&web=Mstislav%20Rostropovich/Dvorak%20Cello%20Concerto%2C%20Tchaikovsky%20Rokoko%20Variation/extralarge&path=%2FNAS%2Fnas%2F14-%E5%B9%BF%E5%B7%9E6%2026%2FDvorak%20Cello%20Concerto%20Rostropovich%20Karajan&metadata=false","duration":942,"samplerate":"48 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: info: ControllerMpd::seek Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:45 volumio volumio[709]: error: null Oct 17 01:29:45 volumio volumio[709]: info: Oct 17 01:29:45 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:45 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:45 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:45 volumio volumio[709]: info: sendMpdCommand status took 1 milliseconds Oct 17 01:29:45 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:45 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:45 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:45 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:45 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":215000,"duration":942,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"532 Kbps","isStreaming":false,"title":"Dvorak / Cello Concerto - 1. Allegro","artist":"Mstislav Rostropovich","album":"Dvorak Cello Concerto, Tchaikovsky Rokoko Variation","uri":"NAS/nas/14-广州6 26/Dvorak Cello Concerto Rostropovich Karajan/(01) [Mstislav Rostropovich] Dvorak - Cello Concerto - 1. Allegro.flac","trackType":"flac"} Oct 17 01:29:45 volumio volumio[709]: verbose: CURRENT POSITION 244 Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:45 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:45 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:45 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:45 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:45 volumio volumio[709]: info: ------------------------------ 22ms Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::volumioPlay Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::play index 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::stop Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::stPlaybackTimer Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::updateTrackBlock Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrackBlock Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::serviceStop Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 244 Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::serviceStop Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::stop Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand stop took 57 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::play index undefined Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 01:29:58 volumio volumio[709]: info: Oct 17 01:29:58 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand stop took 2 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand status took 1 milliseconds Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:58 volumio volumio[709]: info: Oct 17 01:29:58 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:58 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand clear took 1 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac" Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:58 volumio volumio[709]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 01:29:58 volumio volumio[709]: info: ------------------------------ 3ms Oct 17 01:29:58 volumio volumio[709]: error: updateQueue error: null Oct 17 01:29:58 volumio volumio[709]: info: Oct 17 01:29:58 volumio volumio[709]: ---------------------------- MPD announces system playlist update Oct 17 01:29:58 volumio volumio[709]: info: Ignoring MPD Status Update Oct 17 01:29:58 volumio volumio[709]: info: ------------------------------ 2ms Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand add "NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac" took 2 milliseconds Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play Oct 17 01:29:58 volumio volumio[709]: info: ------------------------------ 4ms Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand play took 5 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: Oct 17 01:29:58 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:58 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 17 01:29:58 volumio volumio[709]: info: Oct 17 01:29:58 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand status took 15 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand status took 2 milliseconds Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":743,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"367 Kbps","isStreaming":false,"title":"01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","artist":null,"album":null,"uri":"NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","trackType":"flac"} Oct 17 01:29:58 volumio volumio[709]: verbose: CURRENT POSITION 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus stop Oct 17 01:29:58 volumio volumio[709]: info: ------------------------------ 19ms Oct 17 01:29:58 volumio volumio[709]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 01:29:58 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:29:58 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":743,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","artist":null,"album":null,"uri":"NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","trackType":"flac"} Oct 17 01:29:58 volumio volumio[709]: verbose: CURRENT POSITION 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:29:58 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:58 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:29:58 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:29:58 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:29:58 volumio volumio[709]: info: ------------------------------ 17ms Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::volumioSeek Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::seek Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: info: TRACKBLOCK {"uri":"mnt/NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","service":"mpd","name":"01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=949&web=//extralarge&path=%2FNAS%2Fnas%2F14-%E5%B9%BF%E5%B7%9E6%2026%2FEchoes%20of%20Time%20Lisa%20Batiashvili&metadata=false","duration":743,"samplerate":"48 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: info: ControllerMpd::seek Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:30:02 volumio volumio[709]: error: null Oct 17 01:30:02 volumio volumio[709]: info: Oct 17 01:30:02 volumio volumio[709]: ---------------------------- MPD announces state update: player Oct 17 01:30:02 volumio volumio[709]: info: ControllerMpd::getState Oct 17 01:30:02 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status Oct 17 01:30:02 volumio volumio[709]: info: sendMpdCommand status took 1 milliseconds Oct 17 01:30:02 volumio volumio[709]: verbose: ControllerMpd::parseState Oct 17 01:30:02 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 01:30:02 volumio volumio[709]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 17 01:30:02 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo Oct 17 01:30:02 volumio volumio[709]: info: ControllerMpd::pushState Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::servicePushState Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108000,"duration":743,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"442 Kbps","isStreaming":false,"title":"01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","artist":null,"album":null,"uri":"NAS/nas/14-广州6 26/Echoes of Time Lisa Batiashvili/01 Violin Concerto No. 1 in A minor, Op. 77 (published as Op. 99)- 1. Nocturne- Moderato.flac","trackType":"flac"} Oct 17 01:30:02 volumio volumio[709]: verbose: CURRENT POSITION 256 Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::syncState stateService play Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::syncState currentStatus play Oct 17 01:30:02 volumio volumio[709]: info: Received an update from plugin. extracting info from payload Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:30:02 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:30:02 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:02 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:30:02 volumio volumio[709]: info: ------------------------------ 18ms Oct 17 01:30:06 volumio volumio[709]: info: VolumeController::SetAlsaVolume+ Oct 17 01:30:06 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:30:06 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:06 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:30:06 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:30:06 volumio volumio[709]: info: VolumeController::SetAlsaVolume+ Oct 17 01:30:06 volumio volumio[709]: info: CoreStateMachine::pushState Oct 17 01:30:06 volumio volumio[709]: info: CorePlayQueue::getTrack 256 Oct 17 01:30:06 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:30:06 volumio volumio[709]: info: CoreCommandRouter::volumioPushState Oct 17 01:30:16 volumio volumio[709]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 01:30:16 volumio volumio[709]: Error: connect ETIMEDOUT 108.160.165.147:443 Oct 17 01:30:16 volumio volumio[709]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 17 01:30:16 volumio volumio[709]: errno: -110, Oct 17 01:30:16 volumio volumio[709]: code: 'ETIMEDOUT', Oct 17 01:30:16 volumio volumio[709]: syscall: 'connect', Oct 17 01:30:16 volumio volumio[709]: address: '108.160.165.147', Oct 17 01:30:16 volumio volumio[709]: port: 443 Oct 17 01:30:16 volumio volumio[709]: } Oct 17 01:30:16 volumio volumio[709]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 01:30:17 volumio sudo[9473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-17 01:29 Oct 17 01:30:17 volumio sudo[9473]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"