-- Logs begin at Fri 2024-04-12 01:27:11 CST, end at Fri 2024-04-12 01:38:14 CST. --
Apr 12 01:37:04 volumio volumio[1872]: info: MyVolumio login type: Token
Apr 12 01:37:08 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 12 01:37:08 volumio volumio[1872]: info: Preload queue cleared
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18255
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18245
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18244
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18257
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18252
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18256
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18254
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18246
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18261
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18250
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18248
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18249
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18253
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18247
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18262
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18258
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18251
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18259
Apr 12 01:37:08 volumio volumio[1872]: info: Preloading song: upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18260
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18255 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18245 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18244 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18257 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18252 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18256 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18254 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18246 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18261 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18250 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18248 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18249 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18253 in service upnp_browser
Apr 12 01:37:08 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18247 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18262 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18258 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18251 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18259 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/http://192.168.2.9:50001/ContentDirectory/control@22$@18260 in service upnp_browser
Apr 12 01:37:09 volumio volumio[1872]: info: Preload queue cleared
Apr 12 01:37:09 volumio volumio[1872]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::ClearQueue
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::stop
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::stPlaybackTimer
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::updateTrackBlock
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::getTrackBlock
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:09 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 12 01:37:09 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::serviceStop
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:09 volumio volumio[1872]: info: CoreCommandRouter::serviceStop
Apr 12 01:37:09 volumio volumio[1872]: info: [1712857029972] ControllerUPNPBrowser::stop
Apr 12 01:37:09 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand stop
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::clearPlayQueue
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::saveQueue
Apr 12 01:37:09 volumio volumio[1872]: info: CoreCommandRouter::volumioPushQueue
Apr 12 01:37:09 volumio volumio[1872]: info: CoreStateMachine::addQueueItems
Apr 12 01:37:09 volumio volumio[1872]: info: CorePlayQueue::addQueueItems
Apr 12 01:37:09 volumio volumio[1872]: info: Preload queue cleared
Apr 12 01:37:09 volumio volumio[1872]: info: Adding Item to queue: upnp/folder/http://192.168.2.9:50001/ContentDirectory/control@22$4053
Apr 12 01:37:09 volumio volumio[1872]: info: Exploding uri upnp/folder/http://192.168.2.9:50001/ContentDirectory/control@22$4053 in service upnp_browser
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushQueue
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::saveQueue
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::updateTrackBlock
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrackBlock
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPlay
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::play index 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::stop
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::play index undefined
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::startPlaybackTimer
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: info: [1712857030082] ControllerUPNPBrowser::clearAddPlayTrack
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand stop
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand stop took 111 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand stop took 11 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 8 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 6 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 5 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand clear
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand clear took 11 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true"
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 12 01:37:10 volumio volumio[1872]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 12 01:37:10 volumio volumio[1872]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 32ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 29ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 28ms
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: error: updateQueue error: null
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 24ms
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true"
Apr 12 01:37:10 volumio volumio[1872]: error: updateQueue error: null
Apr 12 01:37:10 volumio volumio[1872]: error: updateQueue error: null
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 158ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 157ms
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand add "http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true" took 3 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand play
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 3ms
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand play took 2 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces system playlist update
Apr 12 01:37:10 volumio volumio[1872]: info: Ignoring MPD Status Update
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 4ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 2ms
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 16 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 20 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 17 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info:
Apr 12 01:37:10 volumio volumio[1872]: ---------------------------- MPD announces state update: player
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::getState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand status
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 16 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 17 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus stop
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 45ms
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 30 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 32 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 30 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand status took 28 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 24 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseState
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus play
Apr 12 01:37:10 volumio volumio[1872]: info: Received an update from plugin. extracting info from payload
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus play
Apr 12 01:37:10 volumio volumio[1872]: info: Received an update from plugin. extracting info from payload
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus play
Apr 12 01:37:10 volumio volumio[1872]: info: Received an update from plugin. extracting info from payload
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 214ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 214ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 210ms
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 158 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: info: sendMpdCommand playlistinfo took 159 milliseconds
Apr 12 01:37:10 volumio volumio[1872]: verbose: ControllerMpd::parseTrackInfo
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus play
Apr 12 01:37:10 volumio volumio[1872]: info: Received an update from plugin. extracting info from payload
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: ControllerMpd::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::servicePushState
Apr 12 01:37:10 volumio volumio[1872]: info: CorePlayQueue::getTrack 0
Apr 12 01:37:10 volumio volumio[1872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=18255.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.2.9:50002/transcoder/genericoder.cgi/id=18255.wav?format=WAV&downsample=true","trackType":""}
Apr 12 01:37:10 volumio volumio[1872]: verbose: CURRENT POSITION 0
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState stateService play
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::syncState currentStatus play
Apr 12 01:37:10 volumio volumio[1872]: info: Received an update from plugin. extracting info from payload
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreStateMachine::pushState
Apr 12 01:37:10 volumio volumio[1872]: info: CoreCommandRouter::volumioPushState
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 296ms
Apr 12 01:37:10 volumio volumio[1872]: info: ------------------------------ 298ms
Apr 12 01:37:15 volumio volumio[1872]: info: CoreCommandRouter::volumioGetState
Apr 12 01:37:26 volumio volumio[1872]: info: CoreCommandRouter::volumioGetState
Apr 12 01:37:34 volumio volumio[1872]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Apr 12 01:37:34 volumio volumio[1872]: info: Retrying Login Due to Network Error
Apr 12 01:37:38 volumio volumio[1872]: info: CoreCommandRouter::volumioGetState
Apr 12 01:37:42 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 12 01:37:44 volumio volumio[1872]: info: CoreCommandRouter::Close All Modals sent
Apr 12 01:37:44 volumio volumio[1872]: info: MyVolumio login type: Token
Apr 12 01:37:49 volumio volumio[1872]: info: CoreCommandRouter::volumioGetState
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Apr 12 01:37:52 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 12 01:38:02 volumio kernel: perf: interrupt took too long (3983 > 3981), lowering kernel.perf_event_max_sample_rate to 50000
Apr 12 01:38:10 volumio volumio[1872]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object]
Apr 12 01:38:10 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions
Apr 12 01:38:11 volumio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 12 01:38:11 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 01:38:11 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root
Apr 12 01:38:11 volumio volumio[1872]: info: MPD Permissions set
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 12 01:38:11 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 12 01:38:11 volumio systemd[1]: Stopping Music Player Daemon...
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 12 01:38:11 volumio volumio[1872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 12 01:38:11 volumio systemd[1]: mpd.service: Succeeded.
Apr 12 01:38:11 volumio systemd[1]: Stopped Music Player Daemon.
Apr 12 01:38:11 volumio systemd[1]: Starting Music Player Daemon...
Apr 12 01:38:13 volumio mpd[2466]: Apr 12 01:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 12 01:38:13 volumio systemd[1]: Started Music Player Daemon.
Apr 12 01:38:13 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root
Apr 12 01:38:13 volumio volumio[1872]: error: updateQueue error: null
Apr 12 01:38:14 volumio volumio[1872]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 12 01:38:14 volumio volumio[1872]: Error: connect ETIMEDOUT 104.244.43.104:443
Apr 12 01:38:14 volumio volumio[1872]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Apr 12 01:38:14 volumio volumio[1872]: errno: -110,
Apr 12 01:38:14 volumio volumio[1872]: code: 'ETIMEDOUT',
Apr 12 01:38:14 volumio volumio[1872]: syscall: 'connect',
Apr 12 01:38:14 volumio volumio[1872]: address: '104.244.43.104',
Apr 12 01:38:14 volumio volumio[1872]: port: 443
Apr 12 01:38:14 volumio volumio[1872]: }
Apr 12 01:38:14 volumio volumio[1872]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 12 01:38:14 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 01:37
Apr 12 01:38:14 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET"
VOLUMIO_VERSION="3.634"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"