-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-04-22 14:04:57 CEST. --
Apr 22 14:03:07 volumio3 kernel: hwmon hwmon1: Voltage normalised
Apr 22 14:03:09 volumio3 volumio[1054]: Generating RSA private key, 4096 bit long modulus (2 primes)
Apr 22 14:03:13 volumio3 kernel: hwmon hwmon1: Undervoltage detected!
Apr 22 14:03:17 volumio3 kernel: hwmon hwmon1: Voltage normalised
Apr 22 14:03:19 volumio3 kernel: hwmon hwmon1: Undervoltage detected!
Apr 22 14:03:27 volumio3 kernel: hwmon hwmon1: Voltage normalised
Apr 22 14:03:31 volumio3 volumio[1054]: ..........................................................................++++
Apr 22 14:03:34 volumio3 kernel: hwmon hwmon1: Undervoltage detected!
Apr 22 14:03:39 volumio3 volumio[1143]: info: Preload queue cleared
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::ClearQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::stop
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::clearPlayQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::saveQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::addQueueItems
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::addQueueItems
Apr 22 14:03:39 volumio3 volumio[1143]: info: Preload queue cleared
Apr 22 14:03:39 volumio3 volumio[1143]: info: Adding Item to queue: webrp/3
Apr 22 14:03:39 volumio3 volumio[1143]: info: Exploding uri webrp/3 in service radio_paradise
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::saveQueue
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::updateTrackBlock
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrackBlock
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPlay
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::play index 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::stop
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::play index undefined
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::startPlaybackTimer
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand stop took 32 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand clear took 6 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand consume 1
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: error: updateQueue error: null
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces state update: options
Apr 22 14:03:39 volumio3 volumio[1143]: error: updateQueue error: null
Apr 22 14:03:39 volumio3 volumio[1143]: error: updateQueue error: null
Apr 22 14:03:39 volumio3 volumio[1143]: error: updateQueue error: null
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 25ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand consume 1 took 19 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 17ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 14ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 12ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: [1713787419551] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac"
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces state update: options
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces state update: options
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces state update: options
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand status took 21 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 19 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand status took 15 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand status took 12 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand status took 9 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:39 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:39 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:03:39 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:39 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 152ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 270ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 268ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 266ms
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info:
Apr 22 14:03:39 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:39 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 278ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: sendMpdCommand play took 243 milliseconds
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 17ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 14ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: ------------------------------ 12ms
Apr 22 14:03:39 volumio3 volumio[1143]: info: [1713787419852] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3
Apr 22 14:03:40 volumio3 volumio[1143]: info: [1713787420598] [RadioParadise] received new metadata: {"time":412,"artist":"DakhaBrakha","title":"Весна","album":"На межі","year":"2009","cover":"https://img.radioparadise.com/covers/l/18577_2715873d-3c4d-4b70-a0b4-187de87acf3a.jpg","cover_med":"https://img.radioparadise.com/covers/m/18577_2715873d-3c4d-4b70-a0b4-187de87acf3a.jpg","cover_small":"https://img.radioparadise.com/covers/s/18577_2715873d-3c4d-4b70-a0b4-187de87acf3a.jpg"}
Apr 22 14:03:40 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:40 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:40 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:40 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18577_2715873d-3c4d-4b70-a0b4-187de87acf3a.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Весна","title":"Весна","artist":"DakhaBrakha","album":"На межі","streaming":true,"disableUiControls":true,"duration":417,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
Apr 22 14:03:40 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:03:40 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:03:40 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 14:03:40 volumio3 volumio[1143]: info: [1713787420611] [RadioParadise] setting new timer with duration of 417000 seconds.
Apr 22 14:03:40 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:40 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:40 volumio3 volumio[1143]: info: Prefetching next song
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 96 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 91 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 88 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 85 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:41 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 64 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 63 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 64 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 64 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 63 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 31 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 27 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 23 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 204ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 200ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 195ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 191ms
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:41 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:41 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:03:41 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:03:41 volumio3 volumio[1143]: info:
Apr 22 14:03:41 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 72ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 66 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 54 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 48 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 47 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 46 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 29ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 26 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 25ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 23 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 21ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand status took 18 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 171ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 152ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 122ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 119ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 32 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 26 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 25 milliseconds
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:03:41 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:03:41 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 132ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 86ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 80ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: ------------------------------ 75ms
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 22 14:03:41 volumio3 volumio[1143]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 22 14:04:17 volumio3 volumio[1143]: info: Preload queue cleared
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::ClearQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::stop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::stPlaybackTimer
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::updateTrackBlock
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrackBlock
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::serviceStop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::serviceStop
Apr 22 14:04:17 volumio3 volumio[1143]: info: ControllerMpd::stop
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::clearPlayQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::saveQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::addQueueItems
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::addQueueItems
Apr 22 14:04:17 volumio3 volumio[1143]: info: Preload queue cleared
Apr 22 14:04:17 volumio3 volumio[1143]: info: Adding Item to queue: webrp/6
Apr 22 14:04:17 volumio3 volumio[1143]: info: Exploding uri webrp/6 in service radio_paradise
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::saveQueue
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::updateTrackBlock
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrackBlock
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPlay
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::play index 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::stop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::play index undefined
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::startPlaybackTimer
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 14:04:17 volumio3 volumio[1143]: info:
Apr 22 14:04:17 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:17 volumio3 volumio[1143]: info: sendMpdCommand stop took 280 milliseconds
Apr 22 14:04:17 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:17 volumio3 volumio[1143]: info: sendMpdCommand stop took 66 milliseconds
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18577_2715873d-3c4d-4b70-a0b4-187de87acf3a.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Весна","title":"Весна","artist":"DakhaBrakha","album":"На межі","streaming":true,"disableUiControls":true,"duration":417,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService stop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: No code
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:17 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:17 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 14:04:17 volumio3 volumio[1143]: info:
Apr 22 14:04:17 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:17 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:17 volumio3 volumio[1143]: info:
Apr 22 14:04:17 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:17 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:17 volumio3 volumio[1143]: info:
Apr 22 14:04:17 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:17 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:17 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand status took 293 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand clear took 225 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand status took 102 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand status took 84 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand status took 79 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64"
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:18 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:04:18 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:04:18 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:18 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:18 volumio3 volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 299ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 296ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 282ms
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: error: updateQueue error: null
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 291ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 276 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64" took 276 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 77ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 74ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 72ms
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:18 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 14:04:18 volumio3 volumio[1143]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 636ms
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info:
Apr 22 14:04:18 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:18 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 84ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: sendMpdCommand play took 35 milliseconds
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 27ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 24ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: ------------------------------ 21ms
Apr 22 14:04:18 volumio3 volumio[1143]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 22 14:04:18 volumio3 volumio[1143]: info: camilladsp spawned new process with pid 1874, instance 1, run: true
Apr 22 14:04:19 volumio3 volumio[1054]: ..........................................................................................................................................++++
Apr 22 14:04:19 volumio3 volumio[1054]: e is 65537 (0x010001)
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1054]: writing RSA key
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 22 14:04:19 volumio3 volumio[1143]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 100 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 95 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 89 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 83 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:19 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:19 volumio3 volumio[1143]: info:
Apr 22 14:04:19 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 53 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 51 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 51 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 59 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 58 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 50 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 45 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: info: sendMpdCommand status took 40 milliseconds
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac-64","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac-64","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:19 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:19 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:19 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:19 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:19 volumio3 volumio[1143]: info: ------------------------------ 356ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 675ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 671ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 669ms
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:20 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:20 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces system playlist update
Apr 22 14:04:20 volumio3 volumio[1143]: info: Ignoring MPD Status Update
Apr 22 14:04:20 volumio3 volumio[1143]: info:
Apr 22 14:04:20 volumio3 volumio[1143]: ---------------------------- MPD announces state update: player
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::getState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 581ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand status took 564 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 553 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 538 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 536 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 534 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 51ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand status took 48 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 46ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand status took 39 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 38ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand status took 34 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseState
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":811,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1051,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1074,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 1132ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 1111ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 1106ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: ------------------------------ 1103ms
Apr 22 14:04:20 volumio3 volumio[1143]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Paradise (AAC 64k)
Apr 22 14:04:20 volumio3 volumio[1143]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Paradise (AAC 64k)
Apr 22 14:04:20 volumio3 volumio[1143]: info: [LastFM] Split composite title into artist: Olivia Ruiz and title: J’traîne des pieds
Apr 22 14:04:20 volumio3 volumio[1143]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare)
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 701 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 694 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 693 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: info: sendMpdCommand playlistinfo took 693 milliseconds
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: ControllerMpd::parseTrackInfo
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2228,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2228,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:20 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: ControllerMpd::pushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CoreCommandRouter::servicePushState
Apr 22 14:04:20 volumio3 volumio[1143]: info: CorePlayQueue::getTrack 0
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Olivia Ruiz - J’traîne des pieds","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"}
Apr 22 14:04:20 volumio3 volumio[1143]: verbose: CURRENT POSITION 0
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreStateMachine::syncState stateService play
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreStateMachine::syncState currentStatus play
Apr 22 14:04:21 volumio3 volumio[1143]: info: Received an update from plugin. extracting info from payload
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreStateMachine::pushState
Apr 22 14:04:21 volumio3 volumio[1143]: info: CoreCommandRouter::volumioPushState
Apr 22 14:04:21 volumio3 volumio[1143]: info: ------------------------------ 1734ms
Apr 22 14:04:21 volumio3 volumio[1143]: info: ------------------------------ 1201ms
Apr 22 14:04:21 volumio3 volumio[1143]: info: ------------------------------ 1196ms
Apr 22 14:04:21 volumio3 volumio[1143]: info: ------------------------------ 1186ms
Apr 22 14:04:21 volumio3 volumio[1143]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 22 14:04:21 volumio3 volumio[1143]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 22 14:04:34 volumio3 kernel: usb 1-1.3: USB disconnect, device number 4
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:25:06:3f:2c:f5 reason=3 locally_generated=1
Apr 22 14:04:34 volumio3 kernel: wlan0: deauthenticating from c0:25:06:3f:2c:f5 by local choice (Reason: 3=DEAUTH_LEAVING)
Apr 22 14:04:34 volumio3 avahi-daemon[438]: Interface wlan0.IPv4 no longer relevant for mDNS.
Apr 22 14:04:34 volumio3 avahi-daemon[438]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.188.43.
Apr 22 14:04:34 volumio3 dhcpcd[786]: wlan0: carrier lost
Apr 22 14:04:34 volumio3 avahi-daemon[438]: Withdrawing address record for 192.168.188.43 on wlan0.
Apr 22 14:04:34 volumio3 volumio[1143]: info: Discovery: A device disappeared from network
Apr 22 14:04:34 volumio3 volumio[1143]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined
Apr 22 14:04:34 volumio3 systemd[1]: Starting Load/Save RF Kill Switch Status...
Apr 22 14:04:34 volumio3 systemd[1]: Stopping ifup for wlan0...
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 22 14:04:34 volumio3 systemd[1]: Started Load/Save RF Kill Switch Status.
Apr 22 14:04:34 volumio3 dhcpcd[786]: wlan0: deleting route to 192.168.188.0/24
Apr 22 14:04:34 volumio3 dhcpcd[786]: wlan0: deleting default route via 192.168.188.1
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 22 14:04:34 volumio3 wpa_supplicant[734]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 22 14:04:35 volumio3 systemd[1]: ifup@wlan0.service: Succeeded.
Apr 22 14:04:35 volumio3 systemd[1]: Stopped ifup for wlan0.
Apr 22 14:04:35 volumio3 ntpd[1451]: ntpd exiting on signal 15 (Terminated)
Apr 22 14:04:35 volumio3 ntpd[1451]: 192.168.188.1 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 195.201.137.97 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 178.63.166.157 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 176.9.44.212 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 131.234.220.231 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 193.203.3.170 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 161.97.164.152 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 131.188.3.220 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 136.243.202.118 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 131.188.3.221 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 80.153.195.191 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 131.234.220.232 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 78.46.204.247 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 systemd[1]: Stopping Network Time Service...
Apr 22 14:04:35 volumio3 ntpd[1451]: 173.249.33.207 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 systemd[1]: ntp.service: Succeeded.
Apr 22 14:04:35 volumio3 ntpd[1451]: 5.9.122.85 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 144.91.116.85 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 ntpd[1451]: 85.215.189.120 local addr 192.168.188.43 ->
Apr 22 14:04:35 volumio3 systemd[1]: Stopped Network Time Service.
Apr 22 14:04:35 volumio3 systemd[1]: Starting Network Time Service...
Apr 22 14:04:35 volumio3 ntpd[2035]: ntpd 4.2.8p12@1.3728-o (1): Starting
Apr 22 14:04:35 volumio3 ntpd[2035]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103
Apr 22 14:04:35 volumio3 systemd[1]: Started Network Time Service.
Apr 22 14:04:35 volumio3 ntpd[2042]: proto: precision = 1.718 usec (-19)
Apr 22 14:04:35 volumio3 dhcpcd[786]: wlan0: removing interface
Apr 22 14:04:35 volumio3 ntpd[2042]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Apr 22 14:04:35 volumio3 ntpd[2042]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Apr 22 14:04:35 volumio3 ntpd[2042]: Listen and drop on 0 v6wildcard [::]:123
Apr 22 14:04:35 volumio3 ntpd[2042]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Apr 22 14:04:35 volumio3 ntpd[2042]: Listen normally on 2 lo 127.0.0.1:123
Apr 22 14:04:35 volumio3 ntpd[2042]: Listening on routing socket on fd #19 for interface updates
Apr 22 14:04:35 volumio3 ntpd[2042]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Apr 22 14:04:35 volumio3 ntpd[2042]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Apr 22 14:04:35 volumio3 dhcpcd-run-hooks[2052]: wlan0: stopping wpa_supplicant
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: Could not read interface wlan0 flags: No such device
Apr 22 14:04:35 volumio3 wpa_supplicant[734]: wlan0: CTRL-EVENT-TERMINATING
Apr 22 14:04:36 volumio3 ntpd[2042]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Apr 22 14:04:37 volumio3 ntpd[2042]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Apr 22 14:04:38 volumio3 ntpd[2042]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Apr 22 14:04:39 volumio3 ntpd[2042]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Apr 22 14:04:39 volumio3 systemd[1]: systemd-rfkill.service: Succeeded.
Apr 22 14:04:40 volumio3 kernel: hwmon hwmon1: Voltage normalised
Apr 22 14:04:40 volumio3 kernel: usb 1-1.4: new high-speed USB device number 5 using dwc_otg
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: New USB device found, idVendor=148f, idProduct=3070, bcdDevice= 1.01
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: Product: 802.11 n WLAN
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: Manufacturer: Ralink
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: SerialNumber: 1.0
Apr 22 14:04:41 volumio3 kernel: usb 1-1.4: reset high-speed USB device number 5 using dwc_otg
Apr 22 14:04:41 volumio3 kernel: ieee80211 phy1: rt2x00_set_rt: Info - RT chipset 3070, rev 0201 detected
Apr 22 14:04:41 volumio3 kernel: ieee80211 phy1: rt2x00_set_rf: Info - RF chipset 0005 detected
Apr 22 14:04:41 volumio3 kernel: ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Apr 22 14:04:41 volumio3 systemd-udevd[2078]: Using default interface naming scheme 'v240'.
Apr 22 14:04:41 volumio3 kernel: mmc0: card aaaa removed
Apr 22 14:04:41 volumio3 kernel: I/O error, dev mmcblk0, sector 0 op 0x1:(WRITE) flags 0x800 phys_seg 0 prio class 2
Apr 22 14:04:41 volumio3 kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653557 starting block 22126163)
Apr 22 14:04:41 volumio3 kernel: Buffer I/O error on device mmcblk0p3, logical block 19392083
Apr 22 14:04:41 volumio3 kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4661762 starting block 22126164)
Apr 22 14:04:41 volumio3 kernel: Buffer I/O error on device mmcblk0p3, logical block 19392084
Apr 22 14:04:41 volumio3 kernel: JBD2: Detected IO errors while flushing file data on mmcblk0p3-8
Apr 22 14:04:41 volumio3 kernel: Aborting journal on device mmcblk0p3-8.
Apr 22 14:04:41 volumio3 kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8.
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2709121: comm node: reading directory lblock 0
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): ext4_journal_check_start:83: comm (d-rfkill): Detected aborted journal
Apr 22 14:04:41 volumio3 systemd-udevd[2078]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Apr 22 14:04:41 volumio3 volumio[1143]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/mmc-SK32G_0x90193cf9-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-uuid/F102-DE29 /dev/disk/by-path/platform-3f202000.mmc-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SK32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x90193cf9","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"1454","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14927516"}
Apr 22 14:04:41 volumio3 systemd[1]: Starting Load/Save RF Kill Switch Status...
Apr 22 14:04:41 volumio3 kernel: EXT4-fs (mmcblk0p3): previous I/O error to superblock detected
Apr 22 14:04:41 volumio3 kernel: EXT4-fs (mmcblk0p3): previous I/O error to superblock detected
Apr 22 14:04:41 volumio3 kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock
Apr 22 14:04:41 volumio3 kernel: EXT4-fs (mmcblk0p3): Remounting filesystem read-only
Apr 22 14:04:41 volumio3 volumio[1143]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/mmc-SK32G_0x90193cf9-part2 /dev/disk/by-path/platform-3f202000.mmc-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-label/volumio /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SK32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x90193cf9","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"1455","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"15069242"}
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2709121: comm node: reading directory lblock 0
Apr 22 14:04:41 volumio3 volumio[1143]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio_data /dev/disk/by-id/mmc-SK32G_0x90193cf9-part3 /dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-path/platform-3f202000.mmc-part3 /dev/disk/by-partuuid/8ca0658c-03","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SK32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x90193cf9","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"1456","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"15201858"}
Apr 22 14:04:41 volumio3 systemd[1]: Stopped target Local File Systems.
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2709121: comm node: reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4655404: comm (umount): reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[2091]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2091]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd-rfkill[2077]: Failed to read state file /var/lib/systemd/rfkill/platform-3f980000.usb-usb-0:1.4:1.0:wlan: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: Started Load/Save RF Kill Switch Status.
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4688101: comm systemd-rfkill: reading directory lblock 0
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4655404: comm (umount): reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[2094]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2094]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2095]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2095]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4655404: comm (umount): reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2096]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2096]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4655404: comm (umount): reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2098]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2098]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4655404: comm (umount): reading directory lblock 0
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2099]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2099]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2100]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2100]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2101]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: Found device RT2870/RT3070 Wireless Adapter.
Apr 22 14:04:41 volumio3 systemd[2101]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2103]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2103]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: Started ifup for wlan0.
Apr 22 14:04:41 volumio3 dhcpcd-run-hooks[2111]: wlan0: starting wpa_supplicant
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2117]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2117]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2122]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2122]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 kernel: ieee80211 phy1: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
Apr 22 14:04:41 volumio3 kernel: ieee80211 phy1: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2125]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2125]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2130]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2130]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2133]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2133]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2134]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2134]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2135]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2135]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too.
Apr 22 14:04:41 volumio3 systemd[1]: Unmounting /boot...
Apr 22 14:04:41 volumio3 systemd[2136]: boot.mount: Failed to execute command: Input/output error
Apr 22 14:04:41 volumio3 systemd[2136]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC
Apr 22 14:04:41 volumio3 systemd[1]: Failed unmounting /boot.
Apr 22 14:04:41 volumio3 systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently.
Apr 22 14:04:41 volumio3 systemd[1]: systemd-fsck@dev-mmcblk0p1.service: Succeeded.
Apr 22 14:04:41 volumio3 systemd[1]: Stopped File System Check on /dev/mmcblk0p1.
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: connected to Access Point `'
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: waiting for carrier
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: carrier acquired
Apr 22 14:04:42 volumio3 sh[2107]: wlan0=wlan0
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: IAID 22:4e:a7:ff
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: adding address fe80::225:22ff:fe4e:a7ff
Apr 22 14:04:42 volumio3 dhcpcd[786]: ipv6_addaddr1: Permission denied
Apr 22 14:04:42 volumio3 dhcpcd[786]: wlan0: carrier lost
Apr 22 14:04:42 volumio3 kernel: hwmon hwmon1: Undervoltage detected!
Apr 22 14:04:43 volumio3 kernel: wlan0: authenticate with c0:25:06:3f:2c:f5
Apr 22 14:04:43 volumio3 kernel: wlan0: 80 MHz not supported, disabling VHT
Apr 22 14:04:43 volumio3 nmbd[547]: [2024/04/22 14:04:43.905649, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces)
Apr 22 14:04:43 volumio3 nmbd[547]: reload_interfaces: No subnets to listen to. Waiting..
Apr 22 14:04:43 volumio3 nmbd[2190]: [2024/04/22 14:04:43.906524, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database)
Apr 22 14:04:43 volumio3 nmbd[2190]: wins_write_database: Can't open /var/lib/samba/wins.dat.2190: Read-only file system
Apr 22 14:04:43 volumio3 kernel: wlan0: send auth to c0:25:06:3f:2c:f5 (try 1/3)
Apr 22 14:04:43 volumio3 kernel: wlan0: authenticated
Apr 22 14:04:43 volumio3 kernel: wlan0: associate with c0:25:06:3f:2c:f5 (try 1/3)
Apr 22 14:04:43 volumio3 kernel: wlan0: RX AssocResp from c0:25:06:3f:2c:f5 (capab=0x431 status=0 aid=1)
Apr 22 14:04:43 volumio3 kernel: wlan0: associated
Apr 22 14:04:44 volumio3 dhcpcd[786]: wlan0: carrier acquired
Apr 22 14:04:44 volumio3 dhcpcd[786]: wlan0: connected to Access Point `kobox'
Apr 22 14:04:44 volumio3 dhcpcd[786]: wlan0: IAID 22:4e:a7:ff
Apr 22 14:04:44 volumio3 dhcpcd[786]: wlan0: rebinding lease of 192.168.188.43
Apr 22 14:04:44 volumio3 dhcpcd[786]: wlan0: soliciting an IPv6 router
Apr 22 14:04:46 volumio3 systemd[1]: systemd-rfkill.service: Succeeded.
Apr 22 14:04:48 volumio3 dhcpcd[786]: wlan0: probing address 192.168.188.43/24
Apr 22 14:04:49 volumio3 kernel: mmc0: host does not support reading read-only switch, assuming write-enable
Apr 22 14:04:49 volumio3 kernel: mmc0: new high speed SDHC card at address aaaa
Apr 22 14:04:49 volumio3 kernel: mmcblk0: mmc0:aaaa SK32G 29.7 GiB
Apr 22 14:04:49 volumio3 kernel: mmcblk0: p1 p2 p3
Apr 22 14:04:49 volumio3 kernel: mmcblk0: mmc0:aaaa SK32G 29.7 GiB (quirks 0x00004000)
Apr 22 14:04:49 volumio3 volumio[1143]: info: Ignoring mount for partition: volumio_data
Apr 22 14:04:49 volumio3 volumio[1143]: info: Ignoring mount for partition: boot
Apr 22 14:04:49 volumio3 volumio[1143]: info: Ignoring mount for partition: volumio
Apr 22 14:04:52 volumio3 dhcpcd[786]: wlan0: leased 192.168.188.43 for 864000 seconds
Apr 22 14:04:52 volumio3 dhcpcd[786]: dhcp_bind: Read-only file system
Apr 22 14:04:52 volumio3 dhcpcd[786]: wlan0: adding route to 192.168.188.0/24
Apr 22 14:04:52 volumio3 avahi-daemon[438]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.188.43.
Apr 22 14:04:52 volumio3 dhcpcd[786]: wlan0: adding default route via 192.168.188.1
Apr 22 14:04:52 volumio3 avahi-daemon[438]: New relevant interface wlan0.IPv4 for mDNS.
Apr 22 14:04:52 volumio3 avahi-daemon[438]: Registering new address record for 192.168.188.43 on wlan0.IPv4.
Apr 22 14:04:53 volumio3 kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #4653466: lblock 0: comm systemd: error -5 reading directory block
Apr 22 14:04:53 volumio3 volumio[1143]: info: Discovery: adding aaf7fe64-0333-457d-94e4-6a8335b1e4ff
Apr 22 14:04:53 volumio3 volumio[1143]: info: Discovery: Found device Volumio3
Apr 22 14:04:53 volumio3 volumio[1143]: info: CoreCommandRouter::volumioGetState
Apr 22 14:04:53 volumio3 nmbd[547]: [2024/04/22 14:04:53.919018, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list)
Apr 22 14:04:53 volumio3 nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Read-only file system
Apr 22 14:04:53 volumio3 nmbd[2244]: [2024/04/22 14:04:53.927885, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database)
Apr 22 14:04:53 volumio3 nmbd[2244]: wins_write_database: Can't open /var/lib/samba/wins.dat.2244: Read-only file system
Apr 22 14:04:54 volumio3 ntpd[2042]: Listen normally on 3 wlan0 192.168.188.43:123
Apr 22 14:04:54 volumio3 ntpd[2042]: new interface(s) found: waking up resolver
Apr 22 14:04:56 volumio3 volumio[1143]: verbose: New Socket.io Connection to 192.168.188.43 from 192.168.188.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 11
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::volumioGetState
Apr 22 14:04:56 volumio3 volumio[1143]: info: Received Get System Info
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 22 14:04:56 volumio3 volumio[1143]: info: Discovery: Getting this device information
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::volumioGetState
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 22 14:04:56 volumio3 volumio[1143]: info: CoreCommandRouter::volumioGetState
Apr 22 14:04:56 volumio3 kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #4653374: lblock 0: comm node: error -5 reading directory block
Apr 22 14:04:56 volumio3 volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 14:04:56 volumio3 volumio[1143]: Error: EIO: i/o error, scandir '/data/plugins/'
Apr 22 14:04:56 volumio3 volumio[1143]: at Object.readdirSync (fs.js:1021:3)
Apr 22 14:04:56 volumio3 volumio[1143]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30)
Apr 22 14:04:56 volumio3 volumio[1143]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1515:29)
Apr 22 14:04:56 volumio3 volumio[1143]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1300:45)
Apr 22 14:04:56 volumio3 volumio[1143]: at Socket.emit (events.js:315:20)
Apr 22 14:04:56 volumio3 volumio[1143]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Apr 22 14:04:56 volumio3 volumio[1143]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Apr 22 14:04:56 volumio3 volumio[1143]: errno: -5,
Apr 22 14:04:56 volumio3 volumio[1143]: syscall: 'scandir',
Apr 22 14:04:56 volumio3 volumio[1143]: code: 'EIO',
Apr 22 14:04:56 volumio3 volumio[1143]: path: '/data/plugins/'
Apr 22 14:04:56 volumio3 volumio[1143]: }
Apr 22 14:04:56 volumio3 volumio[1143]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 14:04:57 volumio3 sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 14:03
Apr 22 14:04:57 volumio3 sudo[2258]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"