-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-07-24 05:37:35 UTC. --
Jul 24 05:36:21 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:36:21 localhost volumio[720]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 24 05:36:21 localhost volumio[720]: info: CoreStateMachine::ClearQueue
Jul 24 05:36:21 localhost volumio[720]: info: CoreStateMachine::stop
Jul 24 05:36:21 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:36:21 localhost volumio[720]: info: CorePlayQueue::clearPlayQueue
Jul 24 05:36:21 localhost volumio[720]: info: CorePlayQueue::saveQueue
Jul 24 05:36:21 localhost volumio[720]: info: CoreCommandRouter::volumioPushQueue
Jul 24 05:36:21 localhost volumio[720]: info: CoreStateMachine::addQueueItems
Jul 24 05:36:21 localhost volumio[720]: info: CorePlayQueue::addQueueItems
Jul 24 05:36:21 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/0
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/0 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/1
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/1 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/2
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/2 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/3
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/3 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/4
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/4 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: info: Adding Item to queue: webkbs/5
Jul 24 05:36:21 localhost volumio[720]: info: Exploding uri webkbs/5 in service personal_radio
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:21 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:36:26 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri
Jul 24 05:36:26 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:36:26 localhost volumio[720]: info: Preloading song: weblinn/0
Jul 24 05:36:26 localhost volumio[720]: info: Preloading song: weblinn/1
Jul 24 05:36:26 localhost volumio[720]: info: Preloading song: weblinn/2
Jul 24 05:36:26 localhost volumio[720]: info: Exploding uri weblinn/0 in service personal_radio
Jul 24 05:36:26 localhost volumio[720]: info: Exploding uri weblinn/1 in service personal_radio
Jul 24 05:36:26 localhost volumio[720]: info: Exploding uri weblinn/2 in service personal_radio
Jul 24 05:36:27 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:36:27 localhost volumio[720]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::ClearQueue
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::stop
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::clearPlayQueue
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::saveQueue
Jul 24 05:36:27 localhost volumio[720]: info: CoreCommandRouter::volumioPushQueue
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::addQueueItems
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::addQueueItems
Jul 24 05:36:27 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:36:27 localhost volumio[720]: info: Adding Item to queue: weblinn/0
Jul 24 05:36:27 localhost volumio[720]: info: Using cached record of: weblinn/0
Jul 24 05:36:27 localhost volumio[720]: info: Adding Item to queue: weblinn/1
Jul 24 05:36:27 localhost volumio[720]: info: Using cached record of: weblinn/1
Jul 24 05:36:27 localhost volumio[720]: info: Adding Item to queue: weblinn/2
Jul 24 05:36:27 localhost volumio[720]: info: Using cached record of: weblinn/2
Jul 24 05:36:27 localhost volumio[720]: info: CoreCommandRouter::volumioPushQueue
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::saveQueue
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::updateTrackBlock
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::getTrackBlock
Jul 24 05:36:27 localhost volumio[720]: info: CoreCommandRouter::volumioPlay
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::play index 2
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::stop
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::play index undefined
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::startPlaybackTimer
Jul 24 05:36:27 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:27 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand stop
Jul 24 05:36:27 localhost volumio[720]: info: sendMpdCommand stop took 6 milliseconds
Jul 24 05:36:27 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand clear
Jul 24 05:36:27 localhost volumio[720]: info: sendMpdCommand clear took 1 milliseconds
Jul 24 05:36:27 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8004/stream"
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info: sendMpdCommand add "http://radio.linnrecords.com:8004/stream" took 9 milliseconds
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 6ms
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 4ms
Jul 24 05:36:27 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand play
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info:
Jul 24 05:36:27 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:27 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 10ms
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 9ms
Jul 24 05:36:27 localhost volumio[720]: info: sendMpdCommand play took 4 milliseconds
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 3ms
Jul 24 05:36:27 localhost volumio[720]: info: ------------------------------ 3ms
Jul 24 05:36:27 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 24 05:36:27 localhost volumio[720]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 24 05:36:27 localhost volumio[720]: info: Pushing Favourites {"uri":"weblinn/2","favourite":false}
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 15 milliseconds
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 16 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 16 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 26ms
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info:
Jul 24 05:36:28 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 11 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 9 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 10 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 3 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand status took 2 milliseconds
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:28 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:28 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 65ms
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 64ms
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 36 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 36 milliseconds
Jul 24 05:36:28 localhost volumio[720]: info: sendMpdCommand playlistinfo took 24 milliseconds
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:28 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:28 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:28 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:28 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:28 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 72ms
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 64ms
Jul 24 05:36:28 localhost volumio[720]: info: ------------------------------ 63ms
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 7ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 6 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 5ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 4 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 3ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 2 milliseconds
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces system playlist update
Jul 24 05:36:29 localhost volumio[720]: info: Ignoring MPD Status Update
Jul 24 05:36:29 localhost volumio[720]: info:
Jul 24 05:36:29 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 9ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 8 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 6 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 6 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 5ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 5 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 5ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand status took 4 milliseconds
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":468,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 38ms
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 38ms
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 37ms
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 31 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 30 milliseconds
Jul 24 05:36:29 localhost volumio[720]: info: sendMpdCommand playlistinfo took 30 milliseconds
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:36:29 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:36:29 localhost volumio[720]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:36:29 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState stateService play
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus play
Jul 24 05:36:29 localhost volumio[720]: info: Received an update from plugin. extracting info from payload
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:29 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 65ms
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 62ms
Jul 24 05:36:29 localhost volumio[720]: info: ------------------------------ 61ms
Jul 24 05:36:38 localhost volumio[720]: info: VolumeController::SetAlsaVolume91
Jul 24 05:36:38 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:38 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:36:38 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:36:41 localhost volumio[720]: info: VolumeController::SetAlsaVolume100
Jul 24 05:36:41 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:36:41 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:36:41 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:08 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri
Jul 24 05:37:08 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:37:09 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::ClearQueue
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::stPlaybackTimer
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::updateTrackBlock
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrackBlock
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::serviceStop
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::serviceStop
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::stop
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand stop
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::clearPlayQueue
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::saveQueue
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushQueue
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::addQueueItems
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::addQueueItems
Jul 24 05:37:09 localhost volumio[720]: info: Preload queue cleared
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/0
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/0 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/1
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/1 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/2
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/2 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/3
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/3 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/4
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/4 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info: Adding Item to queue: webkbs/5
Jul 24 05:37:09 localhost volumio[720]: info: Exploding uri webkbs/5 in service personal_radio
Jul 24 05:37:09 localhost volumio[720]: info:
Jul 24 05:37:09 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand stop took 41 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:37:09 localhost volumio[720]: info:
Jul 24 05:37:09 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:37:09 localhost volumio[720]: info:
Jul 24 05:37:09 localhost volumio[720]: ---------------------------- MPD announces state update: player
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::getState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand status took 5 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand status took 5 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand status took 4 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand status took 3 milliseconds
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseState
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 24 05:37:09 localhost volumio[720]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:37:09 localhost volumio[720]: verbose: ControllerMpd::parseTrackInfo
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:37:09 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState stateService stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: No code
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:37:09 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState stateService stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: No code
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:37:09 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState stateService stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: No code
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: ControllerMpd::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::servicePushState
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dunedin Consort - Benedictus [17E]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 24 05:37:09 localhost volumio[720]: verbose: CURRENT POSITION 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState stateService stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: No code
Jul 24 05:37:09 localhost volumio[720]: info: CoreStateMachine::pushState
Jul 24 05:37:09 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:09 localhost volumio[720]: info: CoreCommandRouter::volumioPushState
Jul 24 05:37:09 localhost volumio[720]: info: ------------------------------ 47ms
Jul 24 05:37:09 localhost volumio[720]: info: ------------------------------ 46ms
Jul 24 05:37:09 localhost volumio[720]: info: ------------------------------ 46ms
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:09 localhost volumio[720]: error: [ControllerPersonalRadio::KBS explodeUri] KBS stream error= Unexpected end of JSON input {"stack":"SyntaxError: Unexpected end of JSON input\n at JSON.parse ()\n at Promise._successFn (/data/plugins/music_service/personal_radio/index.js:547:42)\n at nextTickCallback (/data/plugins/music_service/personal_radio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"}
Jul 24 05:37:17 localhost volumio[720]: info: CoreCommandRouter::volumioGetState
Jul 24 05:37:17 localhost volumio[720]: info: CorePlayQueue::getTrack 2
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: info: Retrieving Cloud Streaming UI
Jul 24 05:37:22 localhost volumio[720]: info: Getting Tidal Cloud Configuration
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: info: Getting Qobuz Cloud Configuration
Jul 24 05:37:22 localhost volumio[720]: info: Asking plugin for UI Config
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: info: Getting Spotify Cloud Configuration
Jul 24 05:37:22 localhost volumio[720]: info: Asking plugin for UI Config
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: info: Saving Spotify Acccount
Jul 24 05:37:22 localhost volumio[720]: info: Got Tidal Cloud Configuration
Jul 24 05:37:22 localhost volumio[720]: info: Got it
Jul 24 05:37:22 localhost volumio[720]: info: Got it
Jul 24 05:37:22 localhost volumio[720]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 05:37:22 localhost volumio[720]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 24 05:37:22 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 24 05:37:26 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 24 05:37:30 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 24 05:37:34 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 24 05:37:35 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jul 24 05:37:35 localhost volumio[720]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jul 24 05:37:35 localhost volumio[720]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 05:37:35 localhost volumio[720]: Error: getaddrinfo -3007
Jul 24 05:37:35 localhost volumio[720]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11)
Jul 24 05:37:35 localhost volumio[720]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10)
Jul 24 05:37:35 localhost volumio[720]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) {
Jul 24 05:37:35 localhost volumio[720]: code: -3007,
Jul 24 05:37:35 localhost volumio[720]: errno: -3007,
Jul 24 05:37:35 localhost volumio[720]: syscall: 'getaddrinfo'
Jul 24 05:37:35 localhost volumio[720]: }
Jul 24 05:37:35 localhost volumio[720]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 05:37:35 localhost sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 05:36
Jul 24 05:37:35 localhost sudo[1491]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d"
VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee"
VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623"
VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST"
VOLUMIO_VERSION="3.661"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"