-- Logs begin at Fri 2025-03-14 15:47:15 UTC, end at Sat 2025-03-15 15:10:03 UTC. --
Mar 15 15:09:00 volumio volumio[4677]: info: Preload queue cleared
Mar 15 15:09:01 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Mar 15 15:09:02 volumio volumio[4677]: info: Preload queue cleared
Mar 15 15:09:04 volumio volumio[4677]: info: CoreStateMachine::startPlaybackTimer
Mar 15 15:09:04 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:04 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:04 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:04 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:04 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:04 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:04 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:04 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 3ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 3ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 3 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 2 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 2ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 1 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280079,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Scream of a Butterfly","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3553.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280079,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Scream of a Butterfly","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3553.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 42ms
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 41ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 32 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280079,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Scream of a Butterfly","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3553.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 50ms
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces system playlist update
Mar 15 15:09:05 volumio volumio[4677]: info: Ignoring MPD Status Update
Mar 15 15:09:05 volumio volumio[4677]: info:
Mar 15 15:09:05 volumio volumio[4677]: ---------------------------- MPD announces state update: player
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 9ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 8 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 9ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 8 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 3ms
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 2 milliseconds
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:05 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:05 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 41ms
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 41ms
Mar 15 15:09:05 volumio volumio[4677]: info: ------------------------------ 35ms
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:05 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:06 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 15 15:09:06 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 15 15:09:06 volumio volumio[4677]: info: Discovery: Getting this device information
Mar 15 15:09:06 volumio volumio[4677]: info: CoreCommandRouter::volumioGetState
Mar 15 15:09:06 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:06 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 15 15:09:08 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Mar 15 15:09:08 volumio volumio[4677]: info: Preload queue cleared
Mar 15 15:09:25 volumio volumio[4677]: info: CoreCommandRouter::volumioGetState
Mar 15 15:09:25 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:36 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 15 15:09:36 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 15 15:09:36 volumio volumio[4677]: info: Discovery: Getting this device information
Mar 15 15:09:36 volumio volumio[4677]: info: CoreCommandRouter::volumioGetState
Mar 15 15:09:36 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:36 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 15 15:09:39 volumio volumio[4677]: info: CoreCommandRouter::volumioGetState
Mar 15 15:09:39 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: info: Retrieving Cloud Streaming UI
Mar 15 15:09:42 volumio volumio[4677]: info: Getting Tidal Cloud Configuration
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: info: Getting Qobuz Cloud Configuration
Mar 15 15:09:42 volumio volumio[4677]: info: Asking plugin for UI Config
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: info: Getting Spotify Cloud Configuration
Mar 15 15:09:42 volumio volumio[4677]: info: Asking plugin for UI Config
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: info: Saving Spotify Acccount
Mar 15 15:09:42 volumio volumio[4677]: info: Got Tidal Cloud Configuration
Mar 15 15:09:42 volumio volumio[4677]: info: Got it
Mar 15 15:09:42 volumio volumio[4677]: info: Got it
Mar 15 15:09:42 volumio volumio[4677]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 15 15:09:42 volumio volumio[4677]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:09:42 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Mar 15 15:09:46 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand update
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand update took 2 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 1 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 61 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 60 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 6 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42091,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 145ms
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info:
Mar 15 15:09:47 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 79 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 57 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 39 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 19 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 18 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 18 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand status took 18 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42091,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42182,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42182,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 258ms
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 200ms
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 199ms
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 53 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 35 milliseconds
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42182,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42182,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:47 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:47 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 143ms
Mar 15 15:09:47 volumio volumio[4677]: info: ------------------------------ 141ms
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:47 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:50 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand rescan
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand rescan took 2 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 5 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 4 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 4 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatetrue
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info:
Mar 15 15:09:51 volumio volumio[4677]: ---------------------------- MPD announces state update: update
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::getState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand status
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 96 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 95 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 51 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 32 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 14 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 4 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 4 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 3 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand status took 2 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: info: Command Router : Notfying DB Updatefalse
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::Close All Modals sent
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseState
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 195ms
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 193ms
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 193ms
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 116 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 97 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: info: sendMpdCommand playlistinfo took 78 milliseconds
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: verbose: ControllerMpd::parseTrackInfo
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ControllerMpd::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::servicePushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46388,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Want you","artist":"Supermax","album":"Something in My Heart","uri":"http://192.168.0.1:8200/MediaItems/3554.mp3","trackType":"mp3"}
Mar 15 15:09:51 volumio volumio[4677]: verbose: CURRENT POSITION 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState stateService play
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::syncState currentStatus play
Mar 15 15:09:51 volumio volumio[4677]: info: Received an update from plugin. extracting info from payload
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::pushState
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getTrack 4
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioPushState
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 272ms
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 180ms
Mar 15 15:09:51 volumio volumio[4677]: info: ------------------------------ 180ms
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreCommandRouter::volumioGetQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CoreStateMachine::getQueue
Mar 15 15:09:51 volumio volumio[4677]: info: CorePlayQueue::getQueue
Mar 15 15:09:54 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:09:58 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:10:02 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 15 15:10:02 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Mar 15 15:10:02 volumio volumio[4677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Mar 15 15:10:02 volumio volumio[4677]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 15 15:10:02 volumio volumio[4677]: Error: getaddrinfo -3007
Mar 15 15:10:02 volumio volumio[4677]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11)
Mar 15 15:10:02 volumio volumio[4677]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10)
Mar 15 15:10:02 volumio volumio[4677]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) {
Mar 15 15:10:02 volumio volumio[4677]: code: -3007,
Mar 15 15:10:02 volumio volumio[4677]: errno: -3007,
Mar 15 15:10:02 volumio volumio[4677]: syscall: 'getaddrinfo'
Mar 15 15:10:02 volumio volumio[4677]: }
Mar 15 15:10:02 volumio volumio[4677]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 15 15:10:03 volumio sudo[7391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 15:09
Mar 15 15:10:03 volumio sudo[7391]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET"
VOLUMIO_VERSION="3.795"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"