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