-- Logs begin at Thu 2025-12-25 05:22:18 CET, end at Mon 2026-02-23 17:40:41 CET. -- Feb 23 17:39:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:04 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:04 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:12 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:12 volumio-primo volumio[747]: info: [1771864752757] ControllerWebradio::stop Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:12 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:12 volumio-primo volumio[747]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489640 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: [1771864752766] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand stop took 19 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:12 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand stop took 11 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:12 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:12 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:12 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand clear took 3 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489640" Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:12 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:12 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:12 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:12 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:12 volumio-primo volumio[747]: info: ------------------------------ 13ms Feb 23 17:39:12 volumio-primo volumio[747]: info: ------------------------------ 12ms Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:12 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:12 volumio-primo volumio[747]: info: Feb 23 17:39:12 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:12 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:12 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:12 volumio-primo volumio[747]: info: ------------------------------ 14ms Feb 23 17:39:12 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 23 17:39:12 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:12 volumio-primo volumio[747]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 23 17:39:12 volumio-primo volumio[747]: info: ------------------------------ 18ms Feb 23 17:39:13 volumio-primo volumio[747]: info: Feb 23 17:39:13 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:13 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:13 volumio-primo volumio[747]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99489640" took 499 milliseconds Feb 23 17:39:13 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:13 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:13 volumio-primo volumio[747]: info: Feb 23 17:39:13 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:13 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:13 volumio-primo volumio[747]: info: Feb 23 17:39:13 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:13 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:13 volumio-primo volumio[747]: info: ------------------------------ 492ms Feb 23 17:39:13 volumio-primo volumio[747]: info: ------------------------------ 7ms Feb 23 17:39:13 volumio-primo volumio[747]: info: sendMpdCommand play took 7 milliseconds Feb 23 17:39:13 volumio-primo volumio[747]: info: ------------------------------ 6ms Feb 23 17:39:14 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 6 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 14 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 11 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"(#1 - 11/512) Radio BluesFlac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"(#1 - 11/512) Radio BluesFlac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"(#1 - 11/512) Radio BluesFlac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"(#1 - 11/512) Radio BluesFlac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 73ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 87ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 87ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 87ms Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:15 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 114ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 113ms Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:15 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:15 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:15 volumio-primo volumio[747]: info: Feb 23 17:39:15 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 56ms Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 56 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 22ms Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 21 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 20ms Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand status took 18 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:15 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:15 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:15 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 106ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 71ms Feb 23 17:39:15 volumio-primo volumio[747]: info: ------------------------------ 69ms Feb 23 17:39:16 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:16 volumio-primo volumio[747]: info: [1771864756106] ControllerWebradio::stop Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:16 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:16 volumio-primo volumio[747]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s265147 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand stop took 33 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand status took 7 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: 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":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:16 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: No code Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: 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":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:16 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: No code Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: 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":"radioblues-flac","artist":"Radio Bluesflac","album":null,"uri":"https://streams.radiomast.io:443/radioblues-flac","trackType":""} Feb 23 17:39:16 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: No code Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:16 volumio-primo volumio[747]: info: ------------------------------ 54ms Feb 23 17:39:16 volumio-primo volumio[747]: info: ------------------------------ 52ms Feb 23 17:39:16 volumio-primo volumio[747]: info: ------------------------------ 49ms Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:16 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:16 volumio-primo volumio[747]: info: [1771864756408] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand stop took 3 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:16 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:16 volumio-primo volumio[747]: info: sendMpdCommand clear took 0 milliseconds Feb 23 17:39:16 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3" Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:16 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:16 volumio-primo volumio[747]: info: Feb 23 17:39:16 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:16 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:18 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 2437ms Feb 23 17:39:18 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand add "https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3" Feb 23 17:39:18 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:18 volumio-primo volumio[747]: info: Feb 23 17:39:18 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:18 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 2440ms Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 2439ms Feb 23 17:39:18 volumio-primo volumio[747]: info: sendMpdCommand add "https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3" took 3 milliseconds Feb 23 17:39:18 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:18 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:18 volumio-primo volumio[747]: info: Feb 23 17:39:18 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:18 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:18 volumio-primo volumio[747]: info: Feb 23 17:39:18 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:18 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:18 volumio-primo volumio[747]: info: sendMpdCommand play took 5 milliseconds Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:18 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","artist":null,"album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 8ms Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","artist":null,"album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","artist":null,"album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","artist":null,"album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","artist":null,"album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 36ms Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 36ms Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 36ms Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 34ms Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:20 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: 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":"Mina & adriano celentano - Specchi riflessi","artist":"Radio Company Easy","album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 42ms Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:20 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:20 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:20 volumio-primo volumio[747]: info: Feb 23 17:39:20 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 23ms Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 23 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 12ms Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 12 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand status took 11 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 17:39:20 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mina & adriano celentano - Specchi riflessi","artist":"Radio Company Easy","album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mina & adriano celentano - Specchi riflessi","artist":"Radio Company Easy","album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:20 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mina & adriano celentano - Specchi riflessi","artist":"Radio Company Easy","album":null,"uri":"https://sphera.fluidstream.eu/ceasy.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 23 17:39:20 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:20 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:20 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 43ms Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 33ms Feb 23 17:39:20 volumio-primo volumio[747]: info: ------------------------------ 32ms Feb 23 17:39:22 volumio-primo volumio[747]: verbose: New Socket.io Connection to 192.168.188.73:3000 from 192.168.188.177 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 23 17:39:22 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:39:22 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:39:22 volumio-primo volumio[747]: info: Discovery: Getting this device information Feb 23 17:39:22 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:22 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:39:23 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:23 volumio-primo volumio[747]: info: [1771864763386] ControllerWebradio::stop Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:23 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:23 volumio-primo volumio[747]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573114 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: [1771864763394] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand stop took 11 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:23 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand stop took 5 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:23 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:23 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand clear took 2 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573114" Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:23 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:23 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:23 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:23 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 10ms Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:23 volumio-primo volumio[747]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 18ms Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573114" took 95 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:23 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: info: Feb 23 17:39:23 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:23 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 90ms Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 90ms Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:23 volumio-primo volumio[747]: info: sendMpdCommand play took 3 milliseconds Feb 23 17:39:23 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:24 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:24 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 19ms Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 22ms Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 22ms Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 16 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 16 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 27ms Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: 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":"","artist":"(#1 - 7/150) Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 47ms Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:24 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:24 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:24 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:24 volumio-primo volumio[747]: info: Feb 23 17:39:24 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: info: ------------------------------ 2ms Feb 23 17:39:24 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:24 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Santa Esmeralda - The House Of The Rising Sun","artist":"Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:24 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:24 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:24 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:24 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Santa Esmeralda - The House Of The Rising Sun","artist":"Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:24 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:24 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:25 volumio-primo volumio[747]: info: ------------------------------ 22ms Feb 23 17:39:25 volumio-primo volumio[747]: info: ------------------------------ 20ms Feb 23 17:39:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 23 17:39:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Santa Esmeralda - The House Of The Rising Sun","artist":"Oldies Online Radio 320 KBS High Quality","album":null,"uri":"https://cast1.torontocast.com:1930/","trackType":"com:1930/"} Feb 23 17:39:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:25 volumio-primo volumio[747]: info: ------------------------------ 28ms Feb 23 17:39:28 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:28 volumio-primo volumio[747]: info: [1771864768234] ControllerWebradio::stop Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:28 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:28 volumio-primo volumio[747]: info: Adding Item to queue: http://37.247.41.49/;stream.mp3 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: [1771864768241] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand stop took 13 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand stop took 6 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:28 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:28 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:28 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand clear took 3 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "http://37.247.41.49/;stream.mp3" Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:28 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:28 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:28 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:28 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:28 volumio-primo volumio[747]: info: ------------------------------ 10ms Feb 23 17:39:28 volumio-primo volumio[747]: info: ------------------------------ 9ms Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:28 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:28 volumio-primo volumio[747]: info: Feb 23 17:39:28 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:28 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:28 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:28 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:28 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 23 17:39:28 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:28 volumio-primo volumio[747]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 23 17:39:28 volumio-primo volumio[747]: info: ------------------------------ 15ms Feb 23 17:39:34 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:34 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:36 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:36 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:36 volumio-primo volumio[747]: info: Adding Item to queue: http://pr320.pinguinradio.com:80 Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:36 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:36 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:36 volumio-primo volumio[747]: info: [1771864776053] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:36 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:39 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand add "http://37.247.41.49/;stream.mp3" Feb 23 17:39:39 volumio-primo volumio[747]: Upnp client error: Error: This socket has been ended by the other party Feb 23 17:39:39 volumio-primo volumio[747]: Upnp client error: Error: This socket has been ended by the other party Feb 23 17:39:39 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:39 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 10851ms Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 10851ms Feb 23 17:39:39 volumio-primo volumio[747]: info: sendMpdCommand stop took 3057 milliseconds Feb 23 17:39:39 volumio-primo volumio[747]: info: sendMpdCommand add "http://37.247.41.49/;stream.mp3" took 4 milliseconds Feb 23 17:39:39 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:39 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:39 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:39 volumio-primo volumio[747]: Upnp client error: Error: This socket has been ended by the other party Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: Upnp client error: Error: This socket has been ended by the other party Feb 23 17:39:39 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:39 volumio-primo volumio[747]: info: sendMpdCommand clear took 5 milliseconds Feb 23 17:39:39 volumio-primo volumio[747]: info: sendMpdCommand play took 5 milliseconds Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:39 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "http://pr320.pinguinradio.com:80" Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: info: Feb 23 17:39:39 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:39 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:39 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:39 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand add "http://pr320.pinguinradio.com:80" Feb 23 17:39:40 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:40 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 996ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 995ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand add "http://pr320.pinguinradio.com:80" took 2 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand play took 4 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 13ms Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 10 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: 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":"pr320.pinguinradio.com:80","artist":null,"album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 56ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 49ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 47ms Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:40 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:40 volumio-primo volumio[747]: info: Feb 23 17:39:40 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 9 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 8ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:40 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:40 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:40 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:40 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 27ms Feb 23 17:39:40 volumio-primo volumio[747]: info: ------------------------------ 25ms Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:44 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:44 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:39:44 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:44 volumio-primo volumio[747]: info: [1771864784818] ControllerWebradio::stop Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:44 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:44 volumio-primo volumio[747]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s153002 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:44 volumio-primo volumio[747]: info: Feb 23 17:39:44 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand stop took 18 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:44 volumio-primo volumio[747]: info: Feb 23 17:39:44 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:44 volumio-primo volumio[747]: info: Feb 23 17:39:44 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:44 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: 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":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:44 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: No code Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: 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":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:44 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: No code Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: 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":"Anna Calvi - God's Lonely Man feat. Iggy Pop ","artist":"Pinguin Indie","album":null,"uri":"http://pr320.pinguinradio.com:80","trackType":"com:80"} Feb 23 17:39:44 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: No code Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:44 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:44 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:44 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:39:44 volumio-primo volumio[747]: info: ------------------------------ 42ms Feb 23 17:39:44 volumio-primo volumio[747]: info: ------------------------------ 42ms Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:45 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:45 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:45 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:45 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:45 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:45 volumio-primo volumio[747]: info: [1771864785079] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:45 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:45 volumio-primo volumio[747]: info: sendMpdCommand stop took 2 milliseconds Feb 23 17:39:45 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:45 volumio-primo volumio[747]: info: Feb 23 17:39:45 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:45 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:45 volumio-primo volumio[747]: info: sendMpdCommand clear took 1 milliseconds Feb 23 17:39:45 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/pls/GRAND_PRIX_RADIO.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M" Feb 23 17:39:45 volumio-primo volumio[747]: info: Feb 23 17:39:45 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:45 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:45 volumio-primo volumio[747]: info: Feb 23 17:39:45 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:45 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:45 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:45 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:46 volumio-primo volumio[747]: info: Feb 23 17:39:46 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:46 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:46 volumio-primo volumio[747]: info: sendMpdCommand load "https://playerservices.streamtheworld.com/pls/GRAND_PRIX_RADIO.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M" took 1273 milliseconds Feb 23 17:39:46 volumio-primo volumio[747]: info: ------------------------------ 1273ms Feb 23 17:39:46 volumio-primo volumio[747]: info: ------------------------------ 1272ms Feb 23 17:39:46 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:39:46 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:39:46 volumio-primo volumio[747]: info: Feb 23 17:39:46 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:46 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:46 volumio-primo volumio[747]: info: Feb 23 17:39:46 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:46 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:46 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:46 volumio-primo volumio[747]: info: sendMpdCommand play took 4 milliseconds Feb 23 17:39:46 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:39:46 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 7 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 6 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 4 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 36ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 47ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 47ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 45ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:48 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: 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":"GRAND_PRIX_RADIO_SC","artist":null,"album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 52ms Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:48 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:48 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:48 volumio-primo volumio[747]: info: Feb 23 17:39:48 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 25ms Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 24 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 11 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 10ms Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand status took 9 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Sportradio","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Sportradio","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:48 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Sportradio","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:48 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:48 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:48 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 50ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 37ms Feb 23 17:39:48 volumio-primo volumio[747]: info: ------------------------------ 35ms Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:53 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:53 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:53 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:53 volumio-primo volumio[747]: info: Feb 23 17:39:53 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:39:53 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:39:53 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 2ms Feb 23 17:39:53 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:53 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 17:39:53 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:53 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Londonbeat - I've Been Thinking About You","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:53 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:53 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:53 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Londonbeat - I've Been Thinking About You","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:53 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:53 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:53 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Londonbeat - I've Been Thinking About You","artist":"Grand Prix Radio","album":null,"uri":"https://25283.live.streamtheworld.com:443/GRAND_PRIX_RADIO_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MTg2NDc4NCwiaXNzIjoidGlzcnYifQ.Ar9vKmtAr2nUbiazDNV0GyZ0l9Pe3SZV3-dGx3Zi01M&gdpr=1","trackType":""} Feb 23 17:39:53 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:39:53 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:53 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:53 volumio-primo volumio[747]: info: ------------------------------ 31ms Feb 23 17:39:54 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:39:59 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:39:59 volumio-primo volumio[747]: info: [1771864799507] ControllerWebradio::stop Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:39:59 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:39:59 volumio-primo volumio[747]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s54815&filter=s:popular Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: [1771864799524] ControllerWebradio::clearAddPlayTrack Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand stop took 20 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand stop took 2 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:59 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:39:59 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:59 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand clear took 4 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand status took 3 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radionl.fm/radionl" Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:39:59 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:59 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:39:59 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:39:59 volumio-primo volumio[747]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 23 17:39:59 volumio-primo volumio[747]: info: ------------------------------ 13ms Feb 23 17:39:59 volumio-primo volumio[747]: info: ------------------------------ 12ms Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:59 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:59 volumio-primo volumio[747]: info: Feb 23 17:39:59 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:39:59 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:39:59 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:39:59 volumio-primo volumio[747]: info: ------------------------------ 12ms Feb 23 17:39:59 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 23 17:39:59 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:39:59 volumio-primo volumio[747]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 23 17:39:59 volumio-primo volumio[747]: info: ------------------------------ 18ms Feb 23 17:40:02 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radionl.fm/radionl" Feb 23 17:40:02 volumio-primo volumio[747]: Upnp client error: Error: This socket has been ended by the other party Feb 23 17:40:02 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:40:02 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:40:02 volumio-primo volumio[747]: info: Feb 23 17:40:02 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:02 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:02 volumio-primo volumio[747]: info: ------------------------------ 2498ms Feb 23 17:40:02 volumio-primo volumio[747]: info: ------------------------------ 2497ms Feb 23 17:40:02 volumio-primo volumio[747]: info: sendMpdCommand add "https://stream.radionl.fm/radionl" took 2 milliseconds Feb 23 17:40:02 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 17:40:02 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:40:02 volumio-primo volumio[747]: info: Feb 23 17:40:02 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:02 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:02 volumio-primo volumio[747]: info: Feb 23 17:40:02 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:02 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:02 volumio-primo volumio[747]: info: ------------------------------ 5ms Feb 23 17:40:02 volumio-primo volumio[747]: info: sendMpdCommand play took 4 milliseconds Feb 23 17:40:02 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:40:02 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:40:04 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:40:04 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 9 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 9 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"radionl","artist":null,"album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 14ms Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 6 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"radionl","artist":null,"album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"radionl","artist":null,"album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 33ms Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 32ms Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:04 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 47ms Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 43ms Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:04 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:04 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:04 volumio-primo volumio[747]: info: Feb 23 17:40:04 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 37ms Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 36 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 13ms Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 12 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand status took 11 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:04 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:04 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:04 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:04 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 56ms Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 33ms Feb 23 17:40:04 volumio-primo volumio[747]: info: ------------------------------ 33ms Feb 23 17:40:09 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:09 volumio-primo volumio[747]: info: CURURI: music-library Feb 23 17:40:09 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:10 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:10 volumio-primo volumio[747]: info: CURURI: music-library/USB Feb 23 17:40:10 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:13 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:13 volumio-primo volumio[747]: info: CURURI: artists:// Feb 23 17:40:13 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:14 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:40:18 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:18 volumio-primo volumio[747]: info: CURURI: music-library Feb 23 17:40:18 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:19 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:19 volumio-primo volumio[747]: info: CURURI: music-library/USB Feb 23 17:40:19 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:19 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 23 17:40:19 volumio-primo volumio[747]: info: CURURI: music-library/USB/SAMSUNG4 Feb 23 17:40:19 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:24 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:40:24 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:40:24 volumio-primo volumio[747]: info: Listing playlists Feb 23 17:40:25 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::ClearQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::stPlaybackTimer Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::serviceStop Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::serviceStop Feb 23 17:40:25 volumio-primo volumio[747]: info: [1771864825552] ControllerWebradio::stop Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::clearPlayQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::addQueueItems Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::addQueueItems Feb 23 17:40:25 volumio-primo volumio[747]: info: Preload queue cleared Feb 23 17:40:25 volumio-primo volumio[747]: info: Adding Item to queue: music-library/USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192 Feb 23 17:40:25 volumio-primo volumio[747]: info: Exploding uri music-library/USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192 in service mpd Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand stop took 23 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: ALBUMART /albumart?cacheid=120&web=The%20Doors/Riders%20On%20The%20Storm%20(R7%20527487)/extralarge&path=%2Fmnt%2FUSB%2FSAMSUNG4%2F2011%20US%20R7%20527487%20Vinyl%20Single%207%20Inch%2024-192%2F01.%20Riders%20On%20The%20Storm%20(Stereo).flac&metadata=false Feb 23 17:40:25 volumio-primo volumio[747]: info: URI /mnt/USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac Feb 23 17:40:25 volumio-primo volumio[747]: info: ALBUMART /albumart?cacheid=120&web=The%20Doors/Riders%20On%20The%20Storm%20(R7%20527487)/extralarge&path=%2Fmnt%2FUSB%2FSAMSUNG4%2F2011%20US%20R7%20527487%20Vinyl%20Single%207%20Inch%2024-192%2F02.%20Riders%20On%20The%20Storm%20(Mono).flac&metadata=false Feb 23 17:40:25 volumio-primo volumio[747]: info: URI /mnt/USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/02. Riders On The Storm (Mono).flac Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::saveQueue Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::updateTrackBlock Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrackBlock Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPlay Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::play index 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::play index undefined Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::startPlaybackTimer Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::clearAddPlayTracks USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 9 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 8 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 7 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand stop took 1 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand clear took 3 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand add "USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac" Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: No code Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: No code Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: 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":"ROB VAN DAAL - NIET HEILIGER DAN... (AMMEHOELA)","artist":"RADIONL","album":null,"uri":"https://stream.radionl.fm/radionl","trackType":"fm/radionl"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: No code Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 44ms Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: error: updateQueue error: null Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 38ms Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand add "USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac" took 37 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 4ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 3ms Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand play Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces system playlist update Feb 23 17:40:25 volumio-primo volumio[747]: info: Ignoring MPD Status Update Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 14ms Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand play took 13 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 12ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 11ms Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 2 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 11 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 10 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 5 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus stop Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 24ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 26ms Feb 23 17:40:25 volumio-primo volumio[747]: info: Feb 23 17:40:25 volumio-primo volumio[747]: ---------------------------- MPD announces state update: player Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::getState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand status Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand status took 1 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseState Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 52ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 51ms Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 51ms Feb 23 17:40:25 volumio-primo volumio[747]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 23 17:40:25 volumio-primo volumio[747]: verbose: ControllerMpd::parseTrackInfo Feb 23 17:40:25 volumio-primo volumio[747]: info: ControllerMpd::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::servicePushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Riders On The Storm (Stereo)","artist":"The Doors","album":"Riders On The Storm (R7 527487)","uri":"USB/SAMSUNG4/2011 US R7 527487 Vinyl Single 7 Inch 24-192/01. Riders On The Storm (Stereo).flac","trackType":"flac"} Feb 23 17:40:25 volumio-primo volumio[747]: verbose: CURRENT POSITION 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState stateService play Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::syncState currentStatus play Feb 23 17:40:25 volumio-primo volumio[747]: info: Received an update from plugin. extracting info from payload Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreStateMachine::pushState Feb 23 17:40:25 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:25 volumio-primo volumio[747]: info: CoreCommandRouter::volumioPushState Feb 23 17:40:25 volumio-primo volumio[747]: info: ------------------------------ 54ms Feb 23 17:40:34 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:40:34 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:35 volumio-primo volumio[747]: verbose: New Socket.io Connection to 192.168.188.73:3000 from 192.168.188.177 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 23 17:40:36 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 17:40:36 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 17:40:36 volumio-primo volumio[747]: info: Discovery: Getting this device information Feb 23 17:40:36 volumio-primo volumio[747]: info: CoreCommandRouter::volumioGetState Feb 23 17:40:36 volumio-primo volumio[747]: info: CorePlayQueue::getTrack 0 Feb 23 17:40:36 volumio-primo volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 17:40:40 volumio-primo volumio[747]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 17:40:40 volumio-primo volumio[747]: Error: read ECONNRESET Feb 23 17:40:40 volumio-primo volumio[747]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Feb 23 17:40:40 volumio-primo volumio[747]: errno: -104, Feb 23 17:40:40 volumio-primo volumio[747]: code: 'ECONNRESET', Feb 23 17:40:40 volumio-primo volumio[747]: syscall: 'read' Feb 23 17:40:40 volumio-primo volumio[747]: } Feb 23 17:40:40 volumio-primo volumio[747]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 17:40:41 volumio-primo sudo[29416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-23 17:39 Feb 23 17:40:41 volumio-primo sudo[29416]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"