-- Logs begin at Mon 2024-08-19 13:48:08 UTC, end at Mon 2024-08-19 14:24:37 UTC. -- Aug 19 14:23:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 14:23:11 volumio volumio[6379]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::ClearQueue Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::stop Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:11 volumio volumio[6379]: verbose: UNSET VOLATILE Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::updateTrackBlock Aug 19 14:23:11 volumio volumio[6379]: info: CorePlayQueue::getTrackBlock Aug 19 14:23:11 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:11 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:11 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::serviceStop Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::serviceStop Aug 19 14:23:12 volumio volumio[6379]: info: [1724077392002] ControllerUPNPBrowser::stop Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::clearPlayQueue Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::saveQueue Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::addQueueItems Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::addQueueItems Aug 19 14:23:12 volumio volumio[6379]: info: Adding Item to queue: upnp/folder/http://192.168.50.78:9791/65bf2aab-f5c9-49ee-ab9e-f3bbde7550aa/upnp.org-ContentDirectory-1/control@0$albums$*a4 Aug 19 14:23:12 volumio volumio[6379]: info: Exploding uri upnp/folder/http://192.168.50.78:9791/65bf2aab-f5c9-49ee-ab9e-f3bbde7550aa/upnp.org-ContentDirectory-1/control@0$albums$*a4 in service upnp_browser Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::saveQueue Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::updateTrackBlock Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrackBlock Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPlay Aug 19 14:23:12 volumio volumio[6379]: verbose: UNSET VOLATILE Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::play index 0 Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::stop Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:12 volumio volumio[6379]: verbose: UNSET VOLATILE Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::play index undefined Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrack 0 Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrack 0 Aug 19 14:23:12 volumio volumio[6379]: info: [1724077392225] ControllerUPNPBrowser::clearAddPlayTrack Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:23:12 volumio volumio[6379]: info: Aug 19 14:23:12 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand stop took 240 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand stop took 17 milliseconds Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:23:12 volumio volumio[6379]: info: Aug 19 14:23:12 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:12 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand status took 22 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand clear took 18 milliseconds Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf" Aug 19 14:23:12 volumio volumio[6379]: error: updateQueue error: null Aug 19 14:23:12 volumio volumio[6379]: info: ------------------------------ 21ms Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:12 volumio volumio[6379]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 14:23:12 volumio volumio[6379]: info: ------------------------------ 48ms Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf" Aug 19 14:23:12 volumio volumio[6379]: info: Aug 19 14:23:12 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:12 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf" took 4 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:23:12 volumio volumio[6379]: info: ------------------------------ 8ms Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand play took 6 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: Aug 19 14:23:12 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:12 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:12 volumio volumio[6379]: info: Aug 19 14:23:12 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand status took 459 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand status took 22 milliseconds Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:12 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrack 0 Aug 19 14:23:12 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":117,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf","trackType":"dsf"} Aug 19 14:23:12 volumio volumio[6379]: verbose: CURRENT POSITION 0 Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:12 volumio volumio[6379]: info: ------------------------------ 540ms Aug 19 14:23:12 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 19 14:23:12 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:12 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:12 volumio volumio[6379]: info: CorePlayQueue::getTrack 0 Aug 19 14:23:12 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":117,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/01*20-*20R.STRAUSS*20Symphonic*20Poem*20Also*20sprach*20Zarathustra*201*20Einlei.dsf","trackType":"dsf"} Aug 19 14:23:12 volumio volumio[6379]: verbose: CURRENT POSITION 0 Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:23:12 volumio volumio[6379]: info: Received an update from plugin. extracting info from payload Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:12 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:12 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:13 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:13 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:13 volumio volumio[6379]: info: ------------------------------ 119ms Aug 19 14:23:13 volumio volumio[6379]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:23:13 volumio volumio[6379]: info: CoreStateMachine::getQueue Aug 19 14:23:13 volumio volumio[6379]: info: CorePlayQueue::getQueue Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::volumioNext Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::next Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::next Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand next Aug 19 14:23:29 volumio volumio[6379]: info: Aug 19 14:23:29 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand next took 80 milliseconds Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand status took 5 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 0 Aug 19 14:23:29 volumio volumio[6379]: 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 19 14:23:29 volumio volumio[6379]: verbose: CURRENT POSITION 0 Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::syncState stateService stop Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::play index undefined Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: info: [1724077409683] ControllerUPNPBrowser::clearAddPlayTrack Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:23:29 volumio volumio[6379]: info: ------------------------------ 66ms Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand stop took 50 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:23:29 volumio volumio[6379]: info: Aug 19 14:23:29 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:29 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand clear took 23 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf" Aug 19 14:23:29 volumio volumio[6379]: error: updateQueue error: null Aug 19 14:23:29 volumio volumio[6379]: info: ------------------------------ 14ms Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf" Aug 19 14:23:29 volumio volumio[6379]: info: Aug 19 14:23:29 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:29 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf" took 4 milliseconds Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:23:29 volumio volumio[6379]: info: ------------------------------ 10ms Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand play took 7 milliseconds Aug 19 14:23:29 volumio volumio[6379]: info: Aug 19 14:23:29 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand status took 9 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:29 volumio volumio[6379]: info: Aug 19 14:23:29 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","trackType":"dsf"} Aug 19 14:23:29 volumio volumio[6379]: verbose: CURRENT POSITION 1 Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:23:29 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:29 volumio volumio[6379]: info: ------------------------------ 73ms Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand status took 60 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:29 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 19 14:23:29 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:29 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:29 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:29 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:29 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":178,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","trackType":"dsf"} Aug 19 14:23:30 volumio volumio[6379]: verbose: CURRENT POSITION 1 Aug 19 14:23:30 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:30 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:23:30 volumio volumio[6379]: info: Received an update from plugin. extracting info from payload Aug 19 14:23:30 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:30 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:30 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:30 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:30 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:30 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:30 volumio volumio[6379]: info: ------------------------------ 159ms Aug 19 14:23:42 volumio volumio[6379]: info: CoreCommandRouter::volumioPause Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::pause Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::servicePause Aug 19 14:23:42 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:42 volumio volumio[6379]: info: CoreCommandRouter::servicePause Aug 19 14:23:42 volumio volumio[6379]: info: [1724077422567] ControllerUPNPBrowser::pause Aug 19 14:23:42 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand pause Aug 19 14:23:42 volumio volumio[6379]: info: Aug 19 14:23:42 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:42 volumio volumio[6379]: info: sendMpdCommand pause took 46 milliseconds Aug 19 14:23:42 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:42 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:42 volumio volumio[6379]: info: sendMpdCommand status took 3 milliseconds Aug 19 14:23:42 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:42 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:42 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 14:23:42 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:42 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:42 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:42 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:42 volumio volumio[6379]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12950,"duration":178,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","trackType":"dsf"} Aug 19 14:23:42 volumio volumio[6379]: verbose: CURRENT POSITION 1 Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::syncState stateService pause Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus pause Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:42 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:42 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:42 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:42 volumio volumio[6379]: info: ------------------------------ 36ms Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::volumioPlay Aug 19 14:23:45 volumio volumio[6379]: verbose: UNSET VOLATILE Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::play index undefined Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:45 volumio volumio[6379]: info: [1724077425026] ControllerUPNPBrowser::resume Aug 19 14:23:45 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:23:45 volumio volumio[6379]: info: Aug 19 14:23:45 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:45 volumio volumio[6379]: info: sendMpdCommand play took 34 milliseconds Aug 19 14:23:45 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:45 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:45 volumio volumio[6379]: info: sendMpdCommand status took 9 milliseconds Aug 19 14:23:45 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:45 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:45 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 14:23:45 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:45 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:45 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13450,"duration":178,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/02*20-*20DEBUSSY*20Children*27s*20corner*206*20Golliwog*27s*20cakewalk.dsf","trackType":"dsf"} Aug 19 14:23:45 volumio volumio[6379]: verbose: CURRENT POSITION 1 Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus pause Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:45 volumio volumio[6379]: info: ------------------------------ 92ms Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::getQueue Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getQueue Aug 19 14:23:45 volumio volumio[6379]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:23:45 volumio volumio[6379]: info: CoreStateMachine::getQueue Aug 19 14:23:45 volumio volumio[6379]: info: CorePlayQueue::getQueue Aug 19 14:23:50 volumio volumio[6379]: info: CoreCommandRouter::volumioNext Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::next Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::stop Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:50 volumio volumio[6379]: verbose: UNSET VOLATILE Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::updateTrackBlock Aug 19 14:23:50 volumio volumio[6379]: info: CorePlayQueue::getTrackBlock Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:50 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:50 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:50 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:50 volumio volumio[6379]: info: CoreStateMachine::serviceStop Aug 19 14:23:50 volumio volumio[6379]: info: CorePlayQueue::getTrack 1 Aug 19 14:23:50 volumio volumio[6379]: info: CoreCommandRouter::serviceStop Aug 19 14:23:50 volumio volumio[6379]: info: [1724077430953] ControllerUPNPBrowser::stop Aug 19 14:23:50 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand stop took 107 milliseconds Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::play index undefined Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:23:51 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:23:51 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:51 volumio volumio[6379]: info: [1724077431063] ControllerUPNPBrowser::clearAddPlayTrack Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::updateTrackBlock Aug 19 14:23:51 volumio volumio[6379]: info: CorePlayQueue::getTrackBlock Aug 19 14:23:51 volumio volumio[6379]: info: Aug 19 14:23:51 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:51 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand stop took 16 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:23:51 volumio volumio[6379]: info: Aug 19 14:23:51 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:51 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand status took 18 milliseconds Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand clear took 11 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf" Aug 19 14:23:51 volumio volumio[6379]: error: updateQueue error: null Aug 19 14:23:51 volumio volumio[6379]: info: ------------------------------ 12ms Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:51 volumio volumio[6379]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 14:23:51 volumio volumio[6379]: info: ------------------------------ 32ms Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf" Aug 19 14:23:51 volumio volumio[6379]: info: Aug 19 14:23:51 volumio volumio[6379]: ---------------------------- MPD announces system playlist update Aug 19 14:23:51 volumio volumio[6379]: info: Ignoring MPD Status Update Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand add "http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf" took 2 milliseconds Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:23:51 volumio volumio[6379]: info: ------------------------------ 10ms Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand play took 11 milliseconds Aug 19 14:23:51 volumio volumio[6379]: info: Aug 19 14:23:51 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:51 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand status took 9 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:51 volumio volumio[6379]: info: Aug 19 14:23:51 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:51 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:51 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:51 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:51 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","trackType":"dsf"} Aug 19 14:23:51 volumio volumio[6379]: verbose: CURRENT POSITION 2 Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:51 volumio volumio[6379]: info: ------------------------------ 62ms Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand status took 47 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:51 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 19 14:23:51 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:51 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:51 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:51 volumio volumio[6379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29,"duration":176,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","trackType":"dsf"} Aug 19 14:23:51 volumio volumio[6379]: verbose: CURRENT POSITION 2 Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::syncState stateService play Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:23:51 volumio volumio[6379]: info: Received an update from plugin. extracting info from payload Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:51 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:51 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:51 volumio volumio[6379]: info: ------------------------------ 150ms Aug 19 14:23:54 volumio volumio[6379]: info: CoreCommandRouter::volumioPause Aug 19 14:23:54 volumio volumio[6379]: info: CoreStateMachine::pause Aug 19 14:23:54 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:54 volumio volumio[6379]: info: CoreStateMachine::servicePause Aug 19 14:23:54 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:54 volumio volumio[6379]: info: CoreCommandRouter::servicePause Aug 19 14:23:54 volumio volumio[6379]: info: [1724077434849] ControllerUPNPBrowser::pause Aug 19 14:23:54 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand pause Aug 19 14:23:54 volumio volumio[6379]: info: Aug 19 14:23:54 volumio volumio[6379]: ---------------------------- MPD announces state update: player Aug 19 14:23:54 volumio volumio[6379]: info: sendMpdCommand pause took 108 milliseconds Aug 19 14:23:54 volumio volumio[6379]: info: ControllerMpd::getState Aug 19 14:23:54 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:23:54 volumio volumio[6379]: info: sendMpdCommand status took 1 milliseconds Aug 19 14:23:54 volumio volumio[6379]: verbose: ControllerMpd::parseState Aug 19 14:23:54 volumio volumio[6379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:23:54 volumio volumio[6379]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 19 14:23:54 volumio volumio[6379]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:23:54 volumio volumio[6379]: info: ControllerMpd::pushState Aug 19 14:23:54 volumio volumio[6379]: info: CoreCommandRouter::servicePushState Aug 19 14:23:54 volumio volumio[6379]: info: CorePlayQueue::getTrack 2 Aug 19 14:23:54 volumio volumio[6379]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3976,"duration":176,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","artist":null,"album":null,"uri":"http://192.168.50.78:9790/minimserver/*/2018/DSD*e5*8f*a4*e5*85*b8/Various*20Artists*20-*20Accuphase*20Sampler*20Disc/03*20-*20J.S.BACH*20Partita*20No.3*203*20Gavotte*20en*20Roudeau.dsf","trackType":"dsf"} Aug 19 14:23:55 volumio volumio[6379]: verbose: CURRENT POSITION 2 Aug 19 14:23:55 volumio volumio[6379]: info: CoreStateMachine::syncState stateService pause Aug 19 14:23:55 volumio volumio[6379]: info: CoreStateMachine::syncState currentStatus pause Aug 19 14:23:55 volumio volumio[6379]: info: CoreStateMachine::pushState Aug 19 14:23:55 volumio volumio[6379]: info: CoreCommandRouter::volumioPushState Aug 19 14:23:55 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:23:55 volumio volumio[6379]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:23:55 volumio volumio[6379]: info: ------------------------------ 67ms Aug 19 14:24:02 volumio volumio[6379]: info: CoreCommandRouter::volumioGetState Aug 19 14:24:02 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 14:24:02 volumio volumio[6379]: info: Listing playlists Aug 19 14:24:02 volumio volumio[6379]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:24:02 volumio volumio[6379]: info: CoreStateMachine::getQueue Aug 19 14:24:02 volumio volumio[6379]: info: CorePlayQueue::getQueue Aug 19 14:24:02 volumio volumio[6379]: info: CoreCommandRouter::volumioGetVisibleSources Aug 19 14:24:02 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 14:24:03 volumio volumio[6379]: info: CoreCommandRouter::volumioGetState Aug 19 14:24:03 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 19 14:24:03 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 14:24:09 volumio volumio[6379]: info: Discovery: Getting this device information Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::volumioGetState Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 19 14:24:09 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getConf Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 19 14:24:14 volumio volumio[6379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 14:24:36 volumio volumio[6379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:24:36 volumio volumio[6379]: { Error: connect ETIMEDOUT 104.244.46.246:443 Aug 19 14:24:36 volumio volumio[6379]: at Object._errnoException (util.js:1022:11) Aug 19 14:24:36 volumio volumio[6379]: at _exceptionWithHostPort (util.js:1044:20) Aug 19 14:24:36 volumio volumio[6379]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 19 14:24:36 volumio volumio[6379]: code: 'ETIMEDOUT', Aug 19 14:24:36 volumio volumio[6379]: errno: 'ETIMEDOUT', Aug 19 14:24:36 volumio volumio[6379]: syscall: 'connect', Aug 19 14:24:36 volumio volumio[6379]: address: '104.244.46.246', Aug 19 14:24:36 volumio volumio[6379]: port: 443 } Aug 19 14:24:36 volumio volumio[6379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:24:37 volumio sudo[7182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 14:23 Aug 19 14:24:37 volumio sudo[7182]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"