-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Thu 2024-10-03 21:42:11 KST. --
Oct 03 21:41:02 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 03 21:41:02 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 03 21:41:05 volumio volumio[839]: info: CoreCommandRouter::volumioGetState
Oct 03 21:41:05 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:10 volumio volumio[839]: info: CoreCommandRouter::volumioGetQueue
Oct 03 21:41:10 volumio volumio[839]: info: CoreStateMachine::getQueue
Oct 03 21:41:10 volumio volumio[839]: info: CorePlayQueue::getQueue
Oct 03 21:41:13 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:13 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:18 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:18 volumio volumio[839]: info: TuneIn handleBrowseUri: tunein/location
Oct 03 21:41:18 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:20 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:20 volumio volumio[839]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101216
Oct 03 21:41:20 volumio volumio[839]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101216
Oct 03 21:41:21 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:35 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:35 volumio volumio[839]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100367
Oct 03 21:41:35 volumio volumio[839]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100367
Oct 03 21:41:36 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:36 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:40 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:40 volumio volumio[839]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101184
Oct 03 21:41:40 volumio volumio[839]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101184
Oct 03 21:41:41 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:41 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:42 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Oct 03 21:41:42 volumio volumio[839]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101184&filter=g152
Oct 03 21:41:42 volumio volumio[839]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101184&filter=g152
Oct 03 21:41:43 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:46 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioReplaceandPlayItems
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::ClearQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::stop
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::clearPlayQueue
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::saveQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::addQueueItems
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::addQueueItems
Oct 03 21:41:46 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:46 volumio volumio[839]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s104649&filter=g152
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::saveQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrackBlock
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPlay
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::play index 0
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::stop
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::play index undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:46 volumio volumio[839]: info: [1727959306352] ControllerWebradio::clearAddPlayTrack
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop
Oct 03 21:41:46 volumio volumio[839]: info: sendMpdCommand stop took 6 milliseconds
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info: sendMpdCommand clear took 17 milliseconds
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand load "http://218.157.19.198:8000"
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 23ms
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://218.157.19.198:8000"
Oct 03 21:41:46 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:46 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:46 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 103ms
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 101ms
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 99ms
Oct 03 21:41:46 volumio volumio[839]: info: sendMpdCommand add "http://218.157.19.198:8000" took 7 milliseconds
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 26ms
Oct 03 21:41:46 volumio volumio[839]: info: sendMpdCommand play took 20 milliseconds
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 21ms
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 17ms
Oct 03 21:41:46 volumio volumio[839]: info: ------------------------------ 16ms
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:46 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:46 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:46 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:46 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:46 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioReplaceandPlayItems
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::ClearQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::stop
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::clearPlayQueue
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::saveQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::addQueueItems
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::addQueueItems
Oct 03 21:41:46 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:41:46 volumio volumio[839]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s104649&filter=g152
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::saveQueue
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrackBlock
Oct 03 21:41:46 volumio volumio[839]: info: CoreCommandRouter::volumioPlay
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::play index 0
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::stop
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::play index undefined
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:46 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer
Oct 03 21:41:46 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:46 volumio volumio[839]: info: [1727959306966] ControllerWebradio::clearAddPlayTrack
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:46 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:46 volumio volumio[839]: info:
Oct 03 21:41:46 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:46 volumio volumio[839]: info: sendMpdCommand status took 353 milliseconds
Oct 03 21:41:46 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:46 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 404 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 402 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 400 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand stop took 78 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 62ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 60 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 58 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 36ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 33 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 33ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 28 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 28ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 25 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 23 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 20 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 18 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 464ms
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:47 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:47 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 61 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 50 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 50 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 38 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand clear took 39 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 38 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 34 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 33 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 33 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 31 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 30 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 29 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 16ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 14ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 12ms
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand load "http://218.157.19.198:8000"
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 492ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 490ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 487ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 144ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 113ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 108ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 103ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 100ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 97ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 94ms
Oct 03 21:41:47 volumio volumio[839]: error: updateQueue error: null
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 29ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 18 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 95ms
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://218.157.19.198:8000"
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand add "http://218.157.19.198:8000" took 2 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 8ms
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand play took 6 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 5ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 5ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 4ms
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 6 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 9 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 7 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 6 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 4 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":null,"album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 19ms
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 15 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 12 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 12 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 12 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 6 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 20 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand status took 27 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":null,"album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":null,"album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":null,"album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 150ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 150ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 150ms
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 110 milliseconds
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: sendMpdCommand playlistinfo took 109 milliseconds
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: 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":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:47 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 249ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 240ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 241ms
Oct 03 21:41:47 volumio volumio[839]: info: ------------------------------ 241ms
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:47 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:47 volumio volumio[839]: info:
Oct 03 21:41:47 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:47 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio sudo[5341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio sudo[5341]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio sudo[5345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio sudo[5345]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:47 volumio systemd[1]: Started peppymeterbasic Daemon.
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio sudo[5341]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:47 volumio sudo[5350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio sudo[5350]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:47 volumio sudo[5346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio sudo[5346]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:47 volumio sudo[5345]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:47 volumio sudo[5354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio sudo[5356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:47 volumio sudo[5354]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:47 volumio sudo[5356]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:47 volumio sudo[5350]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5356]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5354]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5346]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5361]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5363]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5366]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5371]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5373]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5361]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5366]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5363]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5371]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5376]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5373]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5383]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5376]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5387]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5379]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5383]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5387]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5391]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5393]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5391]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5395]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5393]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:48 volumio sudo[5398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5398]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5400]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5406]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:48 volumio sudo[5407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:48 volumio sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:48 volumio sudo[5398]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5395]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5400]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5407]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5406]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5414]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5416]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5414]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5413]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5416]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5421]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5423]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5426]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5423]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5421]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5426]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5430]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5430]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5435]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5432]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5435]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5439]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5432]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5442]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5442]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5439]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5445]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5445]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5448]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5453]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5451]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5453]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5451]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5457]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5459]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5457]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:49 volumio sudo[5462]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:49 volumio sudo[5459]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:49 volumio sudo[5466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:49 volumio sudo[5466]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2461ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 2456 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2460ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 2457 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2354ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 2353 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2352ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 2350 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2350ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 2348 milliseconds
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:50 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:50 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:50 volumio sudo[5462]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio sudo[5466]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Oct 03 21:41:50 volumio sudo[5469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio volumio[839]: info: Ignoring MPD Status Update
Oct 03 21:41:50 volumio volumio[839]: info:
Oct 03 21:41:50 volumio volumio[839]: ---------------------------- MPD announces state update: player
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::getState
Oct 03 21:41:50 volumio sudo[5469]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Oct 03 21:41:50 volumio sudo[5469]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand playlistinfo took 141 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand playlistinfo took 140 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 136ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 122 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 121ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 115 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 106ms
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand status took 92 milliseconds
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseState
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2745ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2745ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2640ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2639ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 2636ms
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand playlistinfo took 145 milliseconds
Oct 03 21:41:50 volumio volumio[839]: info: sendMpdCommand playlistinfo took 144 milliseconds
Oct 03 21:41:50 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ControllerMpd::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"218.157.19.198:8000","artist":"GFN","album":null,"uri":"http://218.157.19.198:8000","trackType":"198:8000"}
Oct 03 21:41:50 volumio volumio[839]: verbose: CURRENT POSITION 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Oct 03 21:41:50 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CorePlayQueue::getTrack 0
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreStateMachine::pushState
Oct 03 21:41:50 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 385ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 371ms
Oct 03 21:41:50 volumio volumio[839]: info: ------------------------------ 353ms
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5472]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5472]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio sudo[5475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5475]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio sudo[5477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5477]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5480]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5477]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio sudo[5475]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5484]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio sudo[5486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5486]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5480]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5486]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio sudo[5484]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:50 volumio sudo[5492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5492]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio sudo[5490]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio sudo[5495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:50 volumio sudo[5495]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:50 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:50 volumio sudo[5492]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5490]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5499]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5502]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5495]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5502]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5505]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5499]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5505]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5509]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5510]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5509]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5512]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5510]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5512]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5520]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5517]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5521]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5526]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5520]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5526]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5517]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5521]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5529]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5531]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5535]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5529]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5540]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5537]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5531]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5535]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5537]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5540]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5545]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5546]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio sudo[5549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5549]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5545]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:51 volumio sudo[5549]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5553]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:51 volumio sudo[5546]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio sudo[5553]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:51 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5556]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5559]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5556]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5565]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5562]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5559]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5569]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5562]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5565]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5569]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5574]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5573]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5574]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5578]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5573]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5580]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5583]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5583]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5578]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5587]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5580]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5590]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5587]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5590]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5593]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5599]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5595]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5599]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5602]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:52 volumio sudo[5605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5605]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:52 volumio sudo[5602]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:52 volumio sudo[5608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:52 volumio sudo[5608]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:53 volumio volumio[839]: info: PeppyMeterBasic ---peppymeterbasic status play
Oct 03 21:41:53 volumio sudo[5605]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:53 volumio sudo[5608]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:53 volumio sudo[5611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:53 volumio sudo[5611]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:53 volumio sudo[5611]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:53 volumio sudo[5614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Oct 03 21:41:53 volumio sudo[5614]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio sudo[5614]: pam_unix(sudo:session): session closed for user root
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:41:53 volumio volumio[839]: info: peppymeterbasic Daemon Started
Oct 03 21:42:00 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Oct 03 21:42:01 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:42:03 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Oct 03 21:42:04 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:42:04 volumio volumio[839]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::volumioGetState
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 03 21:42:04 volumio volumio[839]: info: Received Get System Info
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 03 21:42:04 volumio volumio[839]: info: Discovery: Getting this device information
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::volumioGetState
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 03 21:42:04 volumio volumio[839]: info: CoreCommandRouter::volumioGetState
Oct 03 21:42:04 volumio volumio[839]: info: Listing playlists
Oct 03 21:42:04 volumio volumio[5347]: libpng warning: iCCP: known incorrect sRGB profile
Oct 03 21:42:04 volumio volumio[5347]: libpng warning: iCCP: known incorrect sRGB profile
Oct 03 21:42:05 volumio volumio[5347]: libpng warning: iCCP: known incorrect sRGB profile
Oct 03 21:42:05 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 03 21:42:05 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Oct 03 21:42:05 volumio volumio[839]: info: Preload queue cleared
Oct 03 21:42:08 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Oct 03 21:42:09 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 03 21:42:09 volumio volumio[839]: TypeError: Cannot read property 'length' of undefined
Oct 03 21:42:09 volumio volumio[839]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Oct 03 21:42:09 volumio volumio[839]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Oct 03 21:42:09 volumio volumio[839]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Oct 03 21:42:09 volumio volumio[839]: at Parser.emit (events.js:315:20)
Oct 03 21:42:09 volumio volumio[839]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Oct 03 21:42:09 volumio volumio[839]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Oct 03 21:42:09 volumio volumio[839]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Oct 03 21:42:09 volumio volumio[839]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Oct 03 21:42:09 volumio volumio[839]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Oct 03 21:42:09 volumio volumio[839]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Oct 03 21:42:09 volumio volumio[839]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Oct 03 21:42:09 volumio volumio[839]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Oct 03 21:42:09 volumio volumio[839]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Oct 03 21:42:09 volumio volumio[839]: at IncomingMessage.emit (events.js:327:22)
Oct 03 21:42:09 volumio volumio[839]: at endReadableNT (internal/streams/readable.js:1327:12)
Oct 03 21:42:09 volumio volumio[839]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Oct 03 21:42:09 volumio volumio[839]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 03 21:42:11 volumio sudo[5631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 21:41
Oct 03 21:42:11 volumio sudo[5631]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST"
VOLUMIO_VERSION="3.757"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"