-- Logs begin at Thu 2020-09-24 20:42:43 UTC, end at Thu 2024-05-09 15:11:50 UTC. --
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 09 15:10:01 xxxxx volumio[17453]: info: Discovery: Getting this device information
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetState
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 09 15:10:01 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 09 15:10:06 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 09 15:10:06 xxxxx volumio[17453]: info: CURURI: playlists/02老民谣
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::ClearQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:08 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::serviceStop
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::serviceStop
May 09 15:10:08 xxxxx volumio[17453]: info: [1715267408280] ControllerUPNPBrowser::stop
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::clearPlayQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::saveQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::addQueueItems
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::addQueueItems
May 09 15:10:08 xxxxx volumio[17453]: info: Adding Item to queue: upnp/folder/http://192.168.31.156:1487/ContentDirectory/1de05a06-dcbe-d2ff-b4ad-3c34de1dd10e/control.xml@smb://192.168.1.31/123/音乐/校园民谣珍藏版 1993-2002 3CD/disk2/
May 09 15:10:08 xxxxx volumio[17453]: info: Exploding uri upnp/folder/http://192.168.31.156:1487/ContentDirectory/1de05a06-dcbe-d2ff-b4ad-3c34de1dd10e/control.xml@smb://192.168.1.31/123/音乐/校园民谣珍藏版 1993-2002 3CD/disk2/ in service upnp_browser
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: 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":"06%2520-%2520%25e7%259d%25a1%25e5%259c%25a8%25e6%2588%2591%25e4%25b8%258a%25e9%2593%25ba%25e7%259a%2584%25e5%2585%2584%25e5%25bc%259f%2520-%2520%25e8%2580%2581%25e7%258b%25bc.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/516b066041e65f15a895452140f7765e/06%2520-%2520%25e7%259d%25a1%25e5%259c%25a8%25e6%2588%2591%25e4%25b8%258a%25e9%2593%25ba%25e7%259a%2584%25e5%2585%2584%25e5%25bc%259f%2520-%2520%25e8%2580%2581%25e7%258b%25bc.flac","trackType":"flac"}
May 09 15:10:08 xxxxx volumio[17453]: verbose: CURRENT POSITION 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService stop
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: No code
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 13ms
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::saveQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPlay
May 09 15:10:08 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::play index 0
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:08 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::play index undefined
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:08 xxxxx volumio[17453]: info: [1715267408414] ControllerUPNPBrowser::clearAddPlayTrack
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/416eec2b146d7efcc4700be20d08ad27/06%2520-%2520%25e8%2580%2581%25e5%25b1%258b%2520-%2520%25e8%2580%2581%25e5%25b1%258b.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand clear
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 5ms
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.156:1487/%25/a23c013ede8e283b6eeec53659d4144e/01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac"
May 09 15:10:08 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 80ms
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/a23c013ede8e283b6eeec53659d4144e/01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac"
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand play
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/367d820958853b96547bd3a5e3d982c2/02%2520-%2520%25e5%2585%25b3%25e4%25ba%258e%25e7%2590%2586%25e6%2583%25b3%25e7%259a%2584%25e8%25af%25be%25e5%25a0%2582%25e4%25bd%259c%25e4%25b8%259a%2520-%2520%25e5%2585%25b3%25e4%25ba%258e%25e7%2590%2586%25e6%2583%25b3%25e7%259a%2584%25e8%25af%25be%25e5%25a0%2582%25e4%25bd%259c%25e4%25b8%259a.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/a77adb80d971a2302c564705e9e18531/10%2520-%2520%25e5%2590%258c%25e6%25a1%258c%25e7%259a%2584%25e4%25bd%25a0%25ef%25bc%2588%25e4%25bc%25b4%25e5%25a5%258f%25e7%2589%2588%25ef%25bc%2589%2520-%2520%25e5%2590%258c%25e6%25a1%258c%25e7%259a%2584%25e4%25bd%25a0%25ef%25bc%2588%25e4%25bc%25b4%25e5%25a5%258f%25e7%2589%2588%25ef%25bc%2589.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:08 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/a23c013ede8e283b6eeec53659d4144e/01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac","trackType":"flac"}
May 09 15:10:08 xxxxx volumio[17453]: verbose: CURRENT POSITION 7
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 9ms
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 7ms
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:08 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"772 Kbps","isStreaming":false,"title":"01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/a23c013ede8e283b6eeec53659d4144e/01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac","trackType":"flac"}
May 09 15:10:08 xxxxx volumio[17453]: verbose: CURRENT POSITION 7
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:10:08 xxxxx volumio[17453]: info: Received an update from plugin. extracting info from payload
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 34ms
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b250274981ef434feafced88e1c85ada/04%2520-%2520%25e6%2598%2594%25e6%2597%25a5%25e9%2587%258d%25e6%259d%25a5%2520-%2520%25e6%2598%2594%25e6%2597%25a5%25e9%2587%258d%25e6%259d%25a5.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/c6491fdab54ffa6fb8db963a882a5335/03%2520-%2520%25e8%25bf%2599%25e7%25a7%258d%25e5%25bf%2583%25e6%2583%2585%2520-%2520%25e8%25bf%2599%25e7%25a7%258d%25e5%25bf%2583%25e6%2583%2585.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:08 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:08 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/eb7565a6bc5b798de8bcfba0864e0c97/07%2520-%2520%25e6%259c%2580%25e5%2590%258e%2520-%2520%25e6%259c%2580%25e5%2590%258e.flac"
May 09 15:10:08 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:08 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: info:
May 09 15:10:08 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:08 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:08 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:09 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:09 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:09 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/f2b54103f30cda95f3f70f2feec63bed/09%2520-%2520%25e6%25b2%25a1%25e6%259c%2589%25e6%2583%25b3%25e6%25b3%2595%2520-%2520%25e6%25b2%25a1%25e6%259c%2589%25e6%2583%25b3%25e6%25b3%2595.flac"
May 09 15:10:09 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:09 xxxxx volumio[17453]: info:
May 09 15:10:09 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:09 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:09 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:09 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:09 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:09 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:09 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/afdcf7d9d01668c685e09ec536b07e03/05%2520-%2520%25e7%25ba%25a2%25e8%2589%25b2%25e5%25a4%25a9%25e7%25a9%25ba%2520-%2520%25e7%25ba%25a2%25e8%2589%25b2%25e5%25a4%25a9%25e7%25a9%25ba.flac"
May 09 15:10:09 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:09 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 10
May 09 15:10:09 xxxxx volumio[17453]: info:
May 09 15:10:09 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:09 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:09 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:09 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 09 15:10:10 xxxxx volumio[17453]: info: Discovery: Getting this device information
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetState
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 09 15:10:10 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPlay
May 09 15:10:18 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::play index 7
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:18 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::serviceStop
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::serviceStop
May 09 15:10:18 xxxxx volumio[17453]: info: [1715267418184] ControllerUPNPBrowser::stop
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::play index undefined
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:18 xxxxx volumio[17453]: info: [1715267418198] ControllerUPNPBrowser::clearAddPlayTrack
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:18 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand clear
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac"
May 09 15:10:18 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:18 xxxxx volumio[17453]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 4ms
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac"
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand play
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/a77adb80d971a2302c564705e9e18531/10%2520-%2520%25e5%2590%258c%25e6%25a1%258c%25e7%259a%2584%25e4%25bd%25a0%25ef%25bc%2588%25e4%25bc%25b4%25e5%25a5%258f%25e7%2589%2588%25ef%25bc%2589%2520-%2520%25e5%2590%258c%25e6%25a1%258c%25e7%259a%2584%25e4%25bd%25a0%25ef%25bc%2588%25e4%25bc%25b4%25e5%25a5%258f%25e7%2589%2588%25ef%25bc%2589.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/eb7565a6bc5b798de8bcfba0864e0c97/07%2520-%2520%25e6%259c%2580%25e5%2590%258e%2520-%2520%25e6%259c%2580%25e5%2590%258e.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b250274981ef434feafced88e1c85ada/04%2520-%2520%25e6%2598%2594%25e6%2597%25a5%25e9%2587%258d%25e6%259d%25a5%2520-%2520%25e6%2598%2594%25e6%2597%25a5%25e9%2587%258d%25e6%259d%25a5.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:18 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:18 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:18 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:18 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","trackType":"flac"}
May 09 15:10:18 xxxxx volumio[17453]: verbose: CURRENT POSITION 3
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:18 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:18 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"529 Kbps","isStreaming":false,"title":"08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","trackType":"flac"}
May 09 15:10:18 xxxxx volumio[17453]: verbose: CURRENT POSITION 3
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:10:18 xxxxx volumio[17453]: info: Received an update from plugin. extracting info from payload
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 14ms
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 14ms
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/f2b54103f30cda95f3f70f2feec63bed/09%2520-%2520%25e6%25b2%25a1%25e6%259c%2589%25e6%2583%25b3%25e6%25b3%2595%2520-%2520%25e6%25b2%25a1%25e6%259c%2589%25e6%2583%25b3%25e6%25b3%2595.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/416eec2b146d7efcc4700be20d08ad27/06%2520-%2520%25e8%2580%2581%25e5%25b1%258b%2520-%2520%25e8%2580%2581%25e5%25b1%258b.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/afdcf7d9d01668c685e09ec536b07e03/05%2520-%2520%25e7%25ba%25a2%25e8%2589%25b2%25e5%25a4%25a9%25e7%25a9%25ba%2520-%2520%25e7%25ba%25a2%25e8%2589%25b2%25e5%25a4%25a9%25e7%25a9%25ba.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/367d820958853b96547bd3a5e3d982c2/02%2520-%2520%25e5%2585%25b3%25e4%25ba%258e%25e7%2590%2586%25e6%2583%25b3%25e7%259a%2584%25e8%25af%25be%25e5%25a0%2582%25e4%25bd%259c%25e4%25b8%259a%2520-%2520%25e5%2585%25b3%25e4%25ba%258e%25e7%2590%2586%25e6%2583%25b3%25e7%259a%2584%25e8%25af%25be%25e5%25a0%2582%25e4%25bd%259c%25e4%25b8%259a.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/c6491fdab54ffa6fb8db963a882a5335/03%2520-%2520%25e8%25bf%2599%25e7%25a7%258d%25e5%25bf%2583%25e6%2583%2585%2520-%2520%25e8%25bf%2599%25e7%25a7%258d%25e5%25bf%2583%25e6%2583%2585.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:18 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:18 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/a23c013ede8e283b6eeec53659d4144e/01%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad%2520-%2520%25e5%2586%25ac%25e5%25ad%25a3%25e6%25a0%25a1%25e5%259b%25ad.flac"
May 09 15:10:18 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:18 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:18 xxxxx volumio[17453]: info:
May 09 15:10:18 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:18 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:18 xxxxx volumio[17453]: info: ------------------------------ 6ms
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:18 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 10
May 09 15:10:22 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 09 15:10:22 xxxxx volumio[17453]: info: CURURI: playlists/03老民谣
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::ClearQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:30 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::serviceStop
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::serviceStop
May 09 15:10:30 xxxxx volumio[17453]: info: [1715267430286] ControllerUPNPBrowser::stop
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::clearPlayQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::saveQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::addQueueItems
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::addQueueItems
May 09 15:10:30 xxxxx volumio[17453]: info: Adding Item to queue: upnp/folder/http://192.168.31.156:1487/ContentDirectory/1de05a06-dcbe-d2ff-b4ad-3c34de1dd10e/control.xml@smb://192.168.1.31/123/音乐/校园民谣珍藏版 1993-2002 3CD/disk3/
May 09 15:10:30 xxxxx volumio[17453]: info: Exploding uri upnp/folder/http://192.168.31.156:1487/ContentDirectory/1de05a06-dcbe-d2ff-b4ad-3c34de1dd10e/control.xml@smb://192.168.1.31/123/音乐/校园民谣珍藏版 1993-2002 3CD/disk3/ in service upnp_browser
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: 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":"08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/5e05e20da602f79b7ae54458bc00841f/08%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0%2520-%2520%25e6%2583%25b3%25e4%25bd%25a0.flac","trackType":"flac"}
May 09 15:10:30 xxxxx volumio[17453]: verbose: CURRENT POSITION 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService stop
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: No code
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 12ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::saveQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPlay
May 09 15:10:30 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::play index 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:30 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::play index undefined
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: [1715267430404] ControllerUPNPBrowser::clearAddPlayTrack
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand clear
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.156:1487/%25/60d21375932e0c68a4b1aa4f5fbf3ecd/01%2520-%2520%25e6%2583%2585%25e6%2584%259f%25e5%25be%2580%25e4%25ba%258b%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:30 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/0fd68145241b1408e17aba046fca6d5c/02%2520-%2520%25e4%25bd%25a0%25e6%2598%25af%25e6%259c%258b%25e5%258f%258b%25e5%2590%2597%2520-%2520%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/91de2dd8e44d2d1ccedebfdca2a25359/04%2520-%2520%25e9%259d%2592%25e6%2598%25a5%25e5%2590%258c%25e8%25b7%25af%25e4%25ba%25ba%2520-%2520%25e4%25b8%2581%25e8%2596%2587%25e3%2580%2581%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c%25e3%2580%2581%25e7%25a6%25b9%25e8%2583%25a7%25e3%2580%2581%25e6%259d%258e%25e8%2593%2589.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/60d21375932e0c68a4b1aa4f5fbf3ecd/01%2520-%2520%25e6%2583%2585%25e6%2584%259f%25e5%25be%2580%25e4%25ba%258b%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand play
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b31388b3942aa11e3cc654c33b8c1e42/09%2520-%2520%25e4%25b8%258a%25e5%25ad%25a6%25c2%25b7%25e4%25b8%258a%25e7%258f%25ad%2520-%2520%25e4%25bc%2597%25e6%25ad%258c%25e6%2589%258b.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:30 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:10:30 xxxxx volumio[17453]: verbose: CURRENT POSITION 8
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 13ms
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:30 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":232,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"325 Kbps","isStreaming":false,"title":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:10:30 xxxxx volumio[17453]: verbose: CURRENT POSITION 8
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:10:30 xxxxx volumio[17453]: info: Received an update from plugin. extracting info from payload
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 23ms
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/6cf3d13b5ee3dfa1df85f53d3cac19a7/07%2520-%2520%25e7%2590%2586%25e6%2583%25b3%25e4%25b8%25bb%25e4%25b9%2589%25c2%25b7EMPTY%2520-%2520%25e5%2591%25a8%25e6%2596%2587%25e5%25ae%258f.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/ccfb97d999f9920d1b7c4fba94a142ce/06%2520-%2520%25e4%25b8%258d%25e5%25ae%2589%2520-%2520%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/1e183f4b692c9202badc3b45a0cc3ef3/05%2520-%2520%25e5%2588%259d%25e6%2581%258b%2520-%2520%25e5%25aa%259b%25e5%25aa%259b.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:30 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:30 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b7b9290ea5eebd5b5facd0581448f705/08%2520-%2520%25e6%2583%2585%25e4%25ba%25ba%2520-%2520%25e5%2588%2598%25e8%2588%25aa.flac"
May 09 15:10:30 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:30 xxxxx volumio[17453]: info:
May 09 15:10:30 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:30 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:30 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:30 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:30 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:38 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPlay
May 09 15:10:38 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::play index 2
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:38 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:38 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:38 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:38 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:38 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:38 xxxxx volumio[17453]: info: CoreStateMachine::serviceStop
May 09 15:10:38 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:38 xxxxx volumio[17453]: info: CoreCommandRouter::serviceStop
May 09 15:10:38 xxxxx volumio[17453]: info: [1715267438968] ControllerUPNPBrowser::stop
May 09 15:10:38 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::play index undefined
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:39 xxxxx volumio[17453]: info: [1715267439056] ControllerUPNPBrowser::clearAddPlayTrack
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:39 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand clear
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:39 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:39 xxxxx volumio[17453]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 8ms
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand play
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/ccfb97d999f9920d1b7c4fba94a142ce/06%2520-%2520%25e4%25b8%258d%25e5%25ae%2589%2520-%2520%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/91de2dd8e44d2d1ccedebfdca2a25359/04%2520-%2520%25e9%259d%2592%25e6%2598%25a5%25e5%2590%258c%25e8%25b7%25af%25e4%25ba%25ba%2520-%2520%25e4%25b8%2581%25e8%2596%2587%25e3%2580%2581%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c%25e3%2580%2581%25e7%25a6%25b9%25e8%2583%25a7%25e3%2580%2581%25e6%259d%258e%25e8%2593%2589.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 3
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/6cf3d13b5ee3dfa1df85f53d3cac19a7/07%2520-%2520%25e7%2590%2586%25e6%2583%25b3%25e4%25b8%25bb%25e4%25b9%2589%25c2%25b7EMPTY%2520-%2520%25e5%2591%25a8%25e6%2596%2587%25e5%25ae%258f.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 6
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/1e183f4b692c9202badc3b45a0cc3ef3/05%2520-%2520%25e5%2588%259d%25e6%2581%258b%2520-%2520%25e5%25aa%259b%25e5%25aa%259b.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:39 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:39 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:39 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:39 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":278,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"327 Kbps","isStreaming":false,"title":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:10:39 xxxxx volumio[17453]: verbose: CURRENT POSITION 4
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 8ms
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:39 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:39 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":325,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"327 Kbps","isStreaming":false,"title":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:10:39 xxxxx volumio[17453]: verbose: CURRENT POSITION 4
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:10:39 xxxxx volumio[17453]: info: Received an update from plugin. extracting info from payload
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 14ms
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 4
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/0fd68145241b1408e17aba046fca6d5c/02%2520-%2520%25e4%25bd%25a0%25e6%2598%25af%25e6%259c%258b%25e5%258f%258b%25e5%2590%2597%2520-%2520%25e6%259d%258e%25e6%2599%2593%25e4%25b8%259c.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b7b9290ea5eebd5b5facd0581448f705/08%2520-%2520%25e6%2583%2585%25e4%25ba%25ba%2520-%2520%25e5%2588%2598%25e8%2588%25aa.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 7
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/b31388b3942aa11e3cc654c33b8c1e42/09%2520-%2520%25e4%25b8%258a%25e5%25ad%25a6%25c2%25b7%25e4%25b8%258a%25e7%258f%25ad%2520-%2520%25e4%25bc%2597%25e6%25ad%258c%25e6%2589%258b.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 8
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:39 xxxxx volumio[17453]: info: Prefetching next song
May 09 15:10:39 xxxxx volumio[17453]: info: Doing Prefetch in UPNP
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/60d21375932e0c68a4b1aa4f5fbf3ecd/01%2520-%2520%25e6%2583%2585%25e6%2584%259f%25e5%25be%2580%25e4%25ba%258b%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:39 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: info:
May 09 15:10:39 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:39 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand consume 1
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:39 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 9
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPlay
May 09 15:10:46 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::play index 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:46 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::updateTrackBlock
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrackBlock
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::serviceStop
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::serviceStop
May 09 15:10:46 xxxxx volumio[17453]: info: [1715267446047] ControllerUPNPBrowser::stop
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:46 xxxxx volumio[17453]: info:
May 09 15:10:46 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:10:46 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::play index undefined
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::startPlaybackTimer
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: [1715267446117] ControllerUPNPBrowser::clearAddPlayTrack
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand stop
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand clear
May 09 15:10:46 xxxxx volumio[17453]: info:
May 09 15:10:46 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:46 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:46 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: 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":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:10:46 xxxxx volumio[17453]: verbose: CURRENT POSITION 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService stop
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:46 xxxxx volumio[17453]: info: No code
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:10:46 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:10:46 xxxxx volumio[17453]: info: ------------------------------ 16ms
May 09 15:10:46 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:10:46 xxxxx volumio[17453]: info: ------------------------------ 10ms
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac"
May 09 15:10:46 xxxxx volumio[17453]: info:
May 09 15:10:46 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:10:46 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:10:46 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:10:46 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand play
May 09 15:10:46 xxxxx volumio[17453]: info: ------------------------------ 2ms
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 2
May 09 15:10:46 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 5
May 09 15:11:15 xxxxx systemd-journal[220]: Suppressed 149 messages from /system.slice/volumio.service
May 09 15:11:15 xxxxx volumio[17453]: info:
May 09 15:11:15 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:11:15 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:11:15 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:11:15 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:11:15 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:11:15 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:11:15 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:11:15 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:15 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 1
May 09 15:11:15 xxxxx volumio[17453]: 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":"03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","artist":"群星","album":"校园民谣","uri":"http://192.168.31.156:1487/%25/dc878829458d045d8afdc5efe78da679/03%2520-%2520%25e4%25bd%25a0%25e4%25b8%258d%25e5%259c%25a8%25e7%259a%2584%25e5%258c%2597%25e4%25ba%25ac%25e8%25bf%2598%25e4%25b8%258b%25e7%259d%2580%25e9%259b%25a8%2520-%2520%25e8%2592%258b%25e6%25a2%2585.flac","trackType":"flac"}
May 09 15:11:15 xxxxx volumio[17453]: verbose: CURRENT POSITION 1
May 09 15:11:15 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService stop
May 09 15:11:15 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:15 xxxxx volumio[17453]: info: CoreStateMachine::stPlaybackTimer
May 09 15:11:15 xxxxx volumio[17453]: info: ------------------------------ 11ms
May 09 15:11:15 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:11:15 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:11:15 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:11:15 xxxxx volumio[17453]: info: Clearing queue after UPNP request
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::ClearQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::stop
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService undefined
May 09 15:11:16 xxxxx volumio[17453]: UNSET VOLATILE
May 09 15:11:16 xxxxx volumio[17453]: info: CorePlayQueue::clearPlayQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CorePlayQueue::saveQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushQueue
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: error: updateQueue error: null
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info: Starting UPNP Playback
May 09 15:11:16 xxxxx volumio[17453]: info: Preparing playback through UPNP
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetState
May 09 15:11:16 xxxxx volumio[17453]: info: CorePlayQueue::getTrack 0
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::setConsumeUpdateService mpd
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 0ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 1ms
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:11:16 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces state update: player
May 09 15:11:16 xxxxx volumio[17453]: info:
May 09 15:11:16 xxxxx volumio[17453]: ---------------------------- MPD announces system playlist update
May 09 15:11:16 xxxxx volumio[17453]: info: Ignoring MPD Status Update
May 09 15:11:16 xxxxx volumio[17453]: info: ControllerMpd::getState
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand status
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:11:16 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:11:16 xxxxx volumio[17453]: In UPNP mode
May 09 15:11:16 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"385 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌蔡琴","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000041aqiP3k8MYA.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=8067B0E62B8F7526D567282E066A2066C8B776623A567759D6F7FA01380D8D4BD6F0642902CCEA4CAEB70870197D5989484956D4F415832E&uin=317524287&redirect=1&fromtag=111114","trackType":""}
May 09 15:11:16 xxxxx volumio[17453]: verbose: CURRENT POSITION 0
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus stop
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 11ms
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 11ms
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::parseState
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 09 15:11:16 xxxxx volumio[17453]: verbose: ControllerMpd::parseTrackInfo
May 09 15:11:16 xxxxx volumio[17453]: info: ControllerMpd::pushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::servicePushState
May 09 15:11:16 xxxxx volumio[17453]: In UPNP mode
May 09 15:11:16 xxxxx volumio[17453]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"356 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌蔡琴","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000041aqiP3k8MYA.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=8067B0E62B8F7526D567282E066A2066C8B776623A567759D6F7FA01380D8D4BD6F0642902CCEA4CAEB70870197D5989484956D4F415832E&uin=317524287&redirect=1&fromtag=111114","trackType":""}
May 09 15:11:16 xxxxx volumio[17453]: verbose: CURRENT POSITION 0
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::syncState stateService play
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::syncState currentStatus play
May 09 15:11:16 xxxxx volumio[17453]: info: Received an update from plugin. extracting info from payload
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::pushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioPushState
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 09 15:11:16 xxxxx volumio[17453]: info: ------------------------------ 33ms
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CoreCommandRouter::volumioGetQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CoreStateMachine::getQueue
May 09 15:11:16 xxxxx volumio[17453]: info: CorePlayQueue::getQueue
May 09 15:11:49 xxxxx volumio[17453]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 09 15:11:49 xxxxx volumio[17453]: { Error: connect ETIMEDOUT 162.125.32.9:443
May 09 15:11:49 xxxxx volumio[17453]: at Object._errnoException (util.js:1022:11)
May 09 15:11:49 xxxxx volumio[17453]: at _exceptionWithHostPort (util.js:1044:20)
May 09 15:11:49 xxxxx volumio[17453]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
May 09 15:11:49 xxxxx volumio[17453]: code: 'ETIMEDOUT',
May 09 15:11:49 xxxxx volumio[17453]: errno: 'ETIMEDOUT',
May 09 15:11:49 xxxxx volumio[17453]: syscall: 'connect',
May 09 15:11:49 xxxxx volumio[17453]: address: '162.125.32.9',
May 09 15:11:49 xxxxx volumio[17453]: port: 443 }
May 09 15:11:49 xxxxx volumio[17453]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 09 15:11:50 xxxxx sudo[28731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 15:10
May 09 15:11:50 xxxxx sudo[28731]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
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="74e4cc9de715c64d553d35948d017f973a622b6d"
VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33"
VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020"
VOLUMIO_VERSION="2.834"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"