-- Logs begin at Mon 2025-03-17 19:36:05 +07, end at Mon 2025-03-17 21:00:00 +07. -- Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioNext Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::next Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::next Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand next Mar 17 20:59:00 volumio volumio[1326]: info: sendMpdCommand next took 2 milliseconds Mar 17 20:59:00 volumio volumio[1326]: info: Mar 17 20:59:00 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:00 volumio volumio[1326]: info: Mar 17 20:59:00 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:00 volumio volumio[1326]: info: Mar 17 20:59:00 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:00 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:00 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: CorePlayQueue::getTrack 5 Mar 17 20:59:00 volumio volumio[1326]: 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} Mar 17 20:59:00 volumio volumio[1326]: verbose: CURRENT POSITION 5 Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::stPlaybackTimer Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:00 volumio volumio[1326]: 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} Mar 17 20:59:00 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: No code Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: ------------------------------ 13ms Mar 17 20:59:00 volumio volumio[1326]: info: ------------------------------ 13ms Mar 17 20:59:00 volumio volumio[1326]: info: sendMpdCommand status took 13 milliseconds Mar 17 20:59:00 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:00 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:00 volumio volumio[1326]: 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} Mar 17 20:59:00 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: No code Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:00 volumio volumio[1326]: info: ------------------------------ 21ms Mar 17 20:59:00 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:00 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:00 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:00 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:02 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:02 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:02 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:02 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:02 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:59:08 volumio ntpd[1260]: Soliciting pool server 103.70.115.65 Mar 17 20:59:10 volumio volumio[1326]: info: CoreCommandRouter::volumioPlay Mar 17 20:59:10 volumio volumio[1326]: info: CoreStateMachine::play index undefined Mar 17 20:59:10 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 20:59:10 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:11 volumio volumio[1326]: info: CoreCommandRouter::volumioPlay Mar 17 20:59:11 volumio volumio[1326]: info: CoreStateMachine::play index undefined Mar 17 20:59:11 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 20:59:11 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:13 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:13 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:13 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:13 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:13 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:13 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: Mar 17 20:59:14 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:14 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:14 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 14ms Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 16ms Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 17ms Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 17 20:59:14 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:14 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:14 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:14 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:14 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:14 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:14 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 30ms Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 29ms Mar 17 20:59:14 volumio volumio[1326]: info: ------------------------------ 29ms Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:14 volumio volumio[1326]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.6 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 17 20:59:16 volumio ntpd[1260]: Soliciting pool server 103.70.115.65 Mar 17 20:59:16 volumio volumio[1326]: error: error Mar 17 20:59:18 volumio volumio[1326]: info: Mar 17 20:59:18 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:18 volumio volumio[1326]: info: Mar 17 20:59:18 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:18 volumio volumio[1326]: info: Mar 17 20:59:18 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: 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":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:18 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::stPlaybackTimer Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: 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":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:18 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: No code Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: ------------------------------ 15ms Mar 17 20:59:18 volumio volumio[1326]: info: ------------------------------ 15ms Mar 17 20:59:18 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 17 20:59:18 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:18 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: 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":"Track A - Solo Dancer (Stop! Look! And Listen, Sinner Jim Whitney!)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734107.flac","trackType":"qobuz"} Mar 17 20:59:18 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: No code Mar 17 20:59:18 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:18 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:18 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:18 volumio volumio[1326]: info: ------------------------------ 24ms Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:18 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:20 volumio volumio[1326]: info: Clearing queue after UPNP request Mar 17 20:59:21 volumio volumio[1326]: info: CoreStateMachine::ClearQueue Mar 17 20:59:21 volumio volumio[1326]: info: CoreStateMachine::stop Mar 17 20:59:21 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 20:59:21 volumio volumio[1326]: info: CorePlayQueue::clearPlayQueue Mar 17 20:59:21 volumio volumio[1326]: info: CorePlayQueue::saveQueue Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::volumioPushQueue Mar 17 20:59:21 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: error: updateQueue error: null Mar 17 20:59:21 volumio volumio[1326]: error: updateQueue error: null Mar 17 20:59:21 volumio volumio[1326]: error: updateQueue error: null Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 2ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: Starting UPNP Playback Mar 17 20:59:21 volumio volumio[1326]: info: Preparing playback through UPNP Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:21 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Mar 17 20:59:21 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 0ms Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 0ms Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 0ms Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 0ms Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: Mar 17 20:59:21 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Mar 17 20:59:21 volumio volumio[1326]: info: Ignoring MPD Status Update Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: ------------------------------ 1ms Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:21 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:21 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 4ms Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: Mar 17 20:59:22 volumio volumio[1326]: ---------------------------- MPD announces state update: player Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::getState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 3 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand status took 2 milliseconds Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseState Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:22 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:22 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 16ms Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 16ms Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 17 20:59:22 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:22 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:22 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: ControllerMpd::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Mar 17 20:59:22 volumio volumio[1326]: verbose: In UPNP mode Mar 17 20:59:22 volumio volumio[1326]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track B - Duet Solo Dancers (Hearts’ Beat And Shades In Physical Embraces)","artist":"Charles Mingus","album":"The Black Saint And The Sinner Lady","uri":"http://192.168.1.7:16389/qobuz/4734108.flac","trackType":"qobuz"} Mar 17 20:59:22 volumio volumio[1326]: verbose: CURRENT POSITION 0 Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState stateService play Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:59:22 volumio volumio[1326]: info: Received an update from plugin. extracting info from payload Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::pushState Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 31ms Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 29ms Mar 17 20:59:22 volumio volumio[1326]: info: ------------------------------ 28ms Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:59:22 volumio volumio[1326]: info: CoreStateMachine::getQueue Mar 17 20:59:22 volumio volumio[1326]: info: CorePlayQueue::getQueue Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Mar 17 20:59:22 volumio ntpd[1260]: Soliciting pool server 103.70.115.65 Mar 17 20:59:22 volumio volumio[1326]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Charles%20Mingus/The%20Black%20Saint%20And%20The%20Sinner%20Lady/c882d97e-f8f0-49f2-81e6-d391c254ca9e.png' Mar 17 20:59:33 volumio ntpd[1260]: Soliciting pool server 2a0c:b847:ffff:79::e53c Mar 17 20:59:44 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:44 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:44 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:44 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:44 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:59:45 volumio volumio[1326]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.6 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 17 20:59:49 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:49 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:49 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:49 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:49 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:59:51 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:59:51 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:59:51 volumio volumio[1326]: info: Discovery: Getting this device information Mar 17 20:59:51 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Mar 17 20:59:51 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 21:00:00 volumio volumio[1326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 21:00:00 volumio volumio[1326]: Error: connect ETIMEDOUT 151.101.78.79:443 Mar 17 21:00:00 volumio volumio[1326]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 17 21:00:00 volumio volumio[1326]: errno: -110, Mar 17 21:00:00 volumio volumio[1326]: code: 'ETIMEDOUT', Mar 17 21:00:00 volumio volumio[1326]: syscall: 'connect', Mar 17 21:00:00 volumio volumio[1326]: address: '151.101.78.79', Mar 17 21:00:00 volumio volumio[1326]: port: 443 Mar 17 21:00:00 volumio volumio[1326]: } Mar 17 21:00:00 volumio volumio[1326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 21:00:00 volumio sudo[23668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 20:59 Mar 17 21:00:00 volumio sudo[23668]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"