-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-12 18:16:18 CET. -- Jan 12 18:15:00 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:00 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:02 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:02 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88352 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88353 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88354 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88355 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88356 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88357 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88358 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88359 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88360 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88361 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88362 Jan 12 18:15:02 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88363 Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88352 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88353 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88354 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88355 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88356 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88357 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88358 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88359 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88360 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88361 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88362 in service upnp_browser Jan 12 18:15:02 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88363 in service upnp_browser Jan 12 18:15:05 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::ClearQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::stop Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::clearPlayQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::saveQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::addQueueItems Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::addQueueItems Jan 12 18:15:05 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:05 boss2-02 volumio[909]: info: Adding Item to queue: upnp/folder/http://192.168.1.111:50001/ContentDirectory/control@22$12670 Jan 12 18:15:05 boss2-02 volumio[909]: info: Exploding uri upnp/folder/http://192.168.1.111:50001/ContentDirectory/control@22$12670 in service upnp_browser Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::saveQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrackBlock Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPlay Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::play index 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::stop Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::play index undefined Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: [1736702105217] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand stop took 1 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand clear took 0 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.111:50002/m/NDLNA/88352.flac" Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 29ms Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88352.flac" Jan 12 18:15:05 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:05 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 29ms Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 28ms Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88352.flac" took 1 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 3ms Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand play took 2 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 2ms Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 1ms Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 37 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 39 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 38 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 3ms Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 2 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"658 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 44ms Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:05 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:05 boss2-02 volumio[909]: info: Jan 12 18:15:05 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 3ms Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 3 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"642 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:05 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:05 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"435 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:05 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 62ms Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 63ms Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 26ms Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 20ms Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand status took 19 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"435 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:05 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 28ms Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 48 milliseconds Jan 12 18:15:05 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:05 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:05 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"435 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:05 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:05 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:05 boss2-02 volumio[909]: info: ------------------------------ 76ms Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:05 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::servicePause Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::servicePause Jan 12 18:15:17 boss2-02 volumio[909]: info: [1736702117978] ControllerUPNPBrowser::pause Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand pause Jan 12 18:15:17 boss2-02 volumio[909]: info: Jan 12 18:15:17 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand pause took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:17 boss2-02 volumio[909]: info: Jan 12 18:15:17 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:17 boss2-02 volumio[909]: info: Jan 12 18:15:17 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand status took 2 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:17 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:17 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13476,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:17 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:17 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13476,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:17 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:17 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:17 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13476,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:17 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:17 boss2-02 volumio[909]: info: ------------------------------ 10ms Jan 12 18:15:17 boss2-02 volumio[909]: info: ------------------------------ 10ms Jan 12 18:15:17 boss2-02 volumio[909]: info: ------------------------------ 10ms Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:17 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPlay Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::play index undefined Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: [1736702118908] ControllerUPNPBrowser::resume Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:15:18 boss2-02 volumio[909]: info: Jan 12 18:15:18 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand play took 36 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:18 boss2-02 volumio[909]: info: Jan 12 18:15:18 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:18 boss2-02 volumio[909]: info: Jan 12 18:15:18 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand status took 2 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand status took 0 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:18 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14368,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"649 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:18 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14368,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"649 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:18 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:18 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:18 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14368,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"649 Kbps","isStreaming":false,"title":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:18 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:18 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:18 boss2-02 volumio[909]: info: ------------------------------ 12ms Jan 12 18:15:18 boss2-02 volumio[909]: info: ------------------------------ 12ms Jan 12 18:15:18 boss2-02 volumio[909]: info: ------------------------------ 12ms Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:18 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioNext Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::next Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrackBlock Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::serviceStop Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::serviceStop Jan 12 18:15:19 boss2-02 volumio[909]: info: [1736702119935] ControllerUPNPBrowser::stop Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand stop took 8 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::play index undefined Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: [1736702119944] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrackBlock Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand stop took 1 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand clear took 2 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.111:50002/m/NDLNA/88353.flac" Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: 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":"Mary Pickford","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88352.flac","trackType":"flac"} Jan 12 18:15:19 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: No code Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: 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} Jan 12 18:15:19 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: No code Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: 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} Jan 12 18:15:19 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: No code Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:19 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 20ms Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 18ms Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 18ms Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 19ms Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88353.flac" Jan 12 18:15:19 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:19 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 22ms Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 21ms Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88353.flac" took 1 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:19 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 2ms Jan 12 18:15:19 boss2-02 volumio[909]: info: sendMpdCommand play took 2 milliseconds Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 1ms Jan 12 18:15:19 boss2-02 volumio[909]: info: ------------------------------ 1ms Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:19 boss2-02 volumio[909]: info: Jan 12 18:15:19 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:19 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:19 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 36 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:20 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 37 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 36 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 43ms Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:20 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:20 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:20 boss2-02 volumio[909]: info: Jan 12 18:15:20 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 12ms Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 12 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 8ms Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 6 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 7ms Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand status took 5 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"486 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:20 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"528 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:20 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 68ms Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 68ms Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"528 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:20 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"528 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:20 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:20 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"528 Kbps","isStreaming":false,"title":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:20 boss2-02 volumio[909]: verbose: CURRENT POSITION 1 Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:20 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 48ms Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 42ms Jan 12 18:15:20 boss2-02 volumio[909]: info: ------------------------------ 40ms Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:20 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:30 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:30 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:37 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:37 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88402 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88403 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88404 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88405 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88406 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88407 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88408 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88409 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88410 Jan 12 18:15:37 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88411 Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88402 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88403 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88404 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88405 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88406 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88407 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88408 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88409 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88410 in service upnp_browser Jan 12 18:15:37 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88411 in service upnp_browser Jan 12 18:15:44 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:44 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:46 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:46 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88425 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88426 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88427 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88428 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88429 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88430 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88431 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88432 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88433 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88434 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88435 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88436 Jan 12 18:15:46 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88437 Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88425 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88426 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88427 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88428 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88429 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88430 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88431 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88432 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88433 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88434 in service upnp_browser Jan 12 18:15:46 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88435 in service upnp_browser Jan 12 18:15:47 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88436 in service upnp_browser Jan 12 18:15:47 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88437 in service upnp_browser Jan 12 18:15:49 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::ClearQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrackBlock Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::serviceStop Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 1 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::serviceStop Jan 12 18:15:49 boss2-02 volumio[909]: info: [1736702149331] ControllerUPNPBrowser::stop Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::clearPlayQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::saveQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::addQueueItems Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::addQueueItems Jan 12 18:15:49 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:15:49 boss2-02 volumio[909]: info: Adding Item to queue: upnp/folder/http://192.168.1.111:50001/ContentDirectory/control@22$12685 Jan 12 18:15:49 boss2-02 volumio[909]: info: Exploding uri upnp/folder/http://192.168.1.111:50001/ContentDirectory/control@22$12685 in service upnp_browser Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand stop took 9 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 4 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 3 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 0 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::saveQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::updateTrackBlock Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrackBlock Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPlay Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::play index 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::play index undefined Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: [1736702149358] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand stop took 0 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: 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":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: No code Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: 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":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: No code Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: 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":"It's All In My Head","artist":"Katie Melua","album":"Pictures","uri":"http://192.168.1.111:50002/m/NDLNA/88353.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: No code Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 31ms Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 31ms Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 31ms Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand clear took 20 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.111:50002/m/NDLNA/88425.flac" Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 4ms Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88425.flac" Jan 12 18:15:49 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:49 boss2-02 volumio[909]: error: updateQueue error: null Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 54ms Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand add "http://192.168.1.111:50002/m/NDLNA/88425.flac" took 1 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 2ms Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand play took 2 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 1ms Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 1ms Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 39 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 41 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 40 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 45ms Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces system playlist update Jan 12 18:15:49 boss2-02 volumio[909]: info: Ignoring MPD Status Update Jan 12 18:15:49 boss2-02 volumio[909]: info: Jan 12 18:15:49 boss2-02 volumio[909]: ---------------------------- MPD announces state update: player Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::getState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 7ms Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 7 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 4ms Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 3 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 3ms Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:49 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1397 Kbps","isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:49 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 58ms Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 58ms Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand status took 13 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseState Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1379 Kbps","isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:49 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1379 Kbps","isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:49 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 28ms Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 24ms Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: sendMpdCommand playlistinfo took 44 milliseconds Jan 12 18:15:49 boss2-02 volumio[909]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:15:49 boss2-02 volumio[909]: info: ControllerMpd::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::servicePushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getTrack 0 Jan 12 18:15:49 boss2-02 volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1379 Kbps","isStreaming":false,"title":"Ain't Got Nobody","artist":"Kim Simmonds and Savoy Brown","album":"The Devil To Pay","uri":"http://192.168.1.111:50002/m/NDLNA/88425.flac","trackType":"flac"} Jan 12 18:15:49 boss2-02 volumio[909]: verbose: CURRENT POSITION 0 Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState stateService play Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:15:49 boss2-02 volumio[909]: info: Received an update from plugin. extracting info from payload Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::pushState Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioPushState Jan 12 18:15:49 boss2-02 volumio[909]: info: ------------------------------ 62ms Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CoreStateMachine::getQueue Jan 12 18:15:49 boss2-02 volumio[909]: info: CorePlayQueue::getQueue Jan 12 18:15:57 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:15:57 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:16:05 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:16:05 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:16:09 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:16:09 boss2-02 volumio[909]: info: Preload queue cleared Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88698 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88699 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88700 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88701 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88702 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88703 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88704 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88705 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88706 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88707 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88708 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88709 Jan 12 18:16:09 boss2-02 volumio[909]: info: Preloading song: upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88710 Jan 12 18:16:09 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88698 in service upnp_browser Jan 12 18:16:09 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88699 in service upnp_browser Jan 12 18:16:09 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88700 in service upnp_browser Jan 12 18:16:09 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88701 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88702 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88703 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88704 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88705 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88706 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88707 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88708 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88709 in service upnp_browser Jan 12 18:16:10 boss2-02 volumio[909]: info: Exploding uri upnp/http://192.168.1.111:50001/ContentDirectory/control@22$@88710 in service upnp_browser Jan 12 18:16:18 boss2-02 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 18:16:18 boss2-02 volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 18:16:18 boss2-02 volumio[909]: TypeError: Cannot read property 'length' of undefined Jan 12 18:16:18 boss2-02 volumio[909]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 12 18:16:18 boss2-02 volumio[909]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 12 18:16:18 boss2-02 volumio[909]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 12 18:16:18 boss2-02 volumio[909]: at Parser.emit (events.js:315:20) Jan 12 18:16:18 boss2-02 volumio[909]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 12 18:16:18 boss2-02 volumio[909]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 12 18:16:18 boss2-02 volumio[909]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 12 18:16:18 boss2-02 volumio[909]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 12 18:16:18 boss2-02 volumio[909]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 12 18:16:18 boss2-02 volumio[909]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 12 18:16:18 boss2-02 volumio[909]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 12 18:16:18 boss2-02 volumio[909]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 12 18:16:18 boss2-02 volumio[909]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 12 18:16:18 boss2-02 volumio[909]: at IncomingMessage.emit (events.js:327:22) Jan 12 18:16:18 boss2-02 volumio[909]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 12 18:16:18 boss2-02 volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 12 18:16:18 boss2-02 volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 18:16:18 boss2-02 sudo[11348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 18:15 Jan 12 18:16:18 boss2-02 sudo[11348]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"