-- 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"