-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2024-04-18 13:27:31 CEST. -- Apr 18 13:26:00 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:26:00 volumiorpi5 volumio[1024]: info: CURURI: music-library/USB/SSD2TBEVO/Music/00 MQA Music Apr 18 13:26:00 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:00 volumiorpi5 wpa_supplicant[939]: RRM: Ignoring radio measurement request: Not RRM network Apr 18 13:26:01 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:26:01 volumiorpi5 volumio[1024]: info: CURURI: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022] Apr 18 13:26:01 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CURURI: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:02 volumiorpi5 wpa_supplicant[939]: RRM: Ignoring radio measurement request: Not RRM network Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::ClearQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::stPlaybackTimer Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 1 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::serviceStop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 1 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::serviceStop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::clearPlayQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::addQueueItems Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::addQueueItems Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPlay Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::startPlaybackTimer Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::clearAddPlayTracks USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Apr 18 13:26:02 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 13 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 3 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Apr 18 13:26:02 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 2 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand clear took 2 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac" Apr 18 13:26:02 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Apr 18 13:26:02 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ------------------------------ 2ms Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac" took 1 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand play Apr 18 13:26:02 volumiorpi5 volumio[1024]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ------------------------------ 3ms Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Apr 18 13:26:02 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ------------------------------ 5ms Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand play took 5 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Apr 18 13:26:02 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 10 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Plentiful (Originals) (feat. Pusha T)","artist":"Pusha T","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac","trackType":"flac"} Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus stop Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ------------------------------ 12ms Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1070 Kbps","isStreaming":false,"title":"Plentiful (Originals) (feat. Pusha T)","artist":"Pusha T","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac","trackType":"flac"} Apr 18 13:26:02 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: Received an update from plugin. extracting info from payload Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: ------------------------------ 13ms Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 18 13:26:02 volumiorpi5 volumio[1024]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 18 13:26:04 volumiorpi5 wpa_supplicant[939]: RRM: Ignoring radio measurement request: Not RRM network Apr 18 13:26:06 volumiorpi5 kernel: usb 1-1: USB disconnect, device number 2 Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2905, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: Apr 18 13:26:06 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Detached Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2914, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2921, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2928, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2935, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2942, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2949, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2956, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2963, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2970, instance 1, run: true Apr 18 13:26:06 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2977, instance 1, run: true Apr 18 13:26:06 volumiorpi5 kernel: usb 1-1: new full-speed USB device number 4 using xhci-hcd Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2984, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2991, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 2998, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3005, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3013, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3020, instance 1, run: true Apr 18 13:26:07 volumiorpi5 kernel: usb 1-1: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice=80.33 Apr 18 13:26:07 volumiorpi5 kernel: usb 1-1: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Apr 18 13:26:07 volumiorpi5 kernel: usb 1-1: Product: USB Serial Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3027, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3034, instance 1, run: true Apr 18 13:26:07 volumiorpi5 kernel: usbcore: registered new interface driver usbserial_generic Apr 18 13:26:07 volumiorpi5 kernel: usbserial: USB Serial support registered for generic Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3041, instance 1, run: true Apr 18 13:26:07 volumiorpi5 kernel: usbcore: registered new interface driver ch341 Apr 18 13:26:07 volumiorpi5 kernel: usbserial: USB Serial support registered for ch341-uart Apr 18 13:26:07 volumiorpi5 kernel: ch341 1-1:1.0: ch341-uart converter detected Apr 18 13:26:07 volumiorpi5 kernel: usb 1-1: ch341-uart converter now attached to ttyUSB0 Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3048, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3056, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3063, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3070, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3077, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3084, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3091, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3098, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3105, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3112, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3119, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3126, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3133, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3140, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3147, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3154, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3161, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3168, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3175, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3182, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3189, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3196, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3203, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3210, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3217, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3224, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3233, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3240, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3247, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3254, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3261, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3268, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3275, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3282, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3289, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3296, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3303, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3310, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3317, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3324, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3331, instance 1, run: true Apr 18 13:26:07 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3338, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3345, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3352, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3359, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3366, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3373, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3380, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3387, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3394, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3401, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3408, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3415, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3422, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3429, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3436, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3443, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3450, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3457, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3464, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3471, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3478, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3485, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3492, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3499, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3506, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3513, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3520, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3527, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3534, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3541, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3548, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3555, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3562, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3569, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3576, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3583, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3590, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3599, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3606, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3613, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3620, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3627, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3634, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3641, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3648, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3655, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3662, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3669, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3676, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3683, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3690, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3697, instance 1, run: true Apr 18 13:26:08 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3704, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3711, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3718, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3725, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3732, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3739, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3746, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3753, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3760, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3767, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3774, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3781, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3788, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3795, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3802, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3809, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3816, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3823, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3830, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3837, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3844, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3851, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3858, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3865, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3872, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3879, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3886, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3893, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3900, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3907, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3914, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3921, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3928, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3935, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3942, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3949, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3956, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3963, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3972, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3979, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3986, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 3993, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4000, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4007, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4014, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4021, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4028, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4035, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4042, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4049, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4056, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4063, instance 1, run: true Apr 18 13:26:09 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4070, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4077, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4084, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4091, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4098, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4105, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4112, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4119, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4126, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4133, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4140, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4147, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4154, instance 1, run: true Apr 18 13:26:10 volumiorpi5 kernel: usb 1-1: USB disconnect, device number 4 Apr 18 13:26:10 volumiorpi5 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0 Apr 18 13:26:10 volumiorpi5 kernel: ch341 1-1:1.0: device disconnected Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4161, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4168, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4176, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4183, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4190, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4197, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4204, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4211, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4218, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4225, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4232, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4239, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4246, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4253, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4260, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4267, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4274, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4281, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4288, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4295, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4302, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4309, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4316, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4323, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4330, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4337, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4346, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4353, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4360, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4367, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4374, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4381, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4388, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4395, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4402, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4409, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4416, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4423, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4430, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4437, instance 1, run: true Apr 18 13:26:10 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4444, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4451, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4458, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4465, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4472, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4479, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4486, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4493, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4500, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4507, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4515, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4522, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4529, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4536, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4543, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4550, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4557, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4564, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4571, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4578, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4585, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4592, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4599, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4606, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4613, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4620, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4627, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4634, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4641, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4648, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4655, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4662, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4669, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4676, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4683, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4690, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4697, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4704, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4711, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4718, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4727, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4734, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4741, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4748, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4755, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4762, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4769, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4776, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4783, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4790, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4797, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4804, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4811, instance 1, run: true Apr 18 13:26:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4818, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4825, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4832, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4839, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4846, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4853, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4860, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4867, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4874, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4881, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4888, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4895, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4902, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4909, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4916, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4923, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4930, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4937, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4944, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4951, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4958, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4965, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4972, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4979, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4986, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 4993, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5000, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5007, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5014, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5021, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5028, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5035, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5042, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5049, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5056, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5063, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5070, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5077, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5084, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5091, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5100, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5107, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5114, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5121, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5128, instance 1, run: true Apr 18 13:26:12 volumiorpi5 kernel: usb 1-1: new high-speed USB device number 5 using xhci-hcd Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5135, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5142, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5149, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5156, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5163, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5170, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5177, instance 1, run: true Apr 18 13:26:12 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5184, instance 1, run: true Apr 18 13:26:12 volumiorpi5 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.13 Apr 18 13:26:12 volumiorpi5 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 18 13:26:12 volumiorpi5 kernel: usb 1-1: Product: SMSL USB AUDIO Apr 18 13:26:12 volumiorpi5 kernel: usb 1-1: Manufacturer: SMSL Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5191, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5198, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5205, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5212, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5219, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5226, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5233, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5240, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5247, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5254, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5261, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5268, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5275, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5282, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5289, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5296, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5303, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5310, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5317, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5324, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5331, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5338, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5345, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5352, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5360, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5367, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5374, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5381, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5388, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5395, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5402, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5409, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5416, instance 1, run: true Apr 18 13:26:13 volumiorpi5 kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5423, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5430, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5437, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5444, instance 1, run: true Apr 18 13:26:13 volumiorpi5 kernel: hid-generic 0003:152A:85DD.0005: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-1/input3 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Apr 18 13:26:13 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Attached Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::Close All Modals sent Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Device 5 does not have any Mixer Control Available Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Disabling external Volume Control Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Preparing to generate the ALSA configuration file Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Reading ALSA contributions from plugins. Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Asound.conf file unchanged, so no further update is needed Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Output device has changed, restarting MPD Apr 18 13:26:13 volumiorpi5 sudo[5471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 13:26:13 volumiorpi5 sudo[5471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Output device has changed, restarting Shairport Sync Apr 18 13:26:13 volumiorpi5 sudo[5471]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:13 volumiorpi5 sudo[5474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 13:26:13 volumiorpi5 sudo[5474]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:13 volumiorpi5 systemd[1]: Stopping Music Player Daemon... Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: MPD Permissions set Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 systemd[1]: mpd.service: Succeeded. Apr 18 13:26:13 volumiorpi5 systemd[1]: Stopped Music Player Daemon. Apr 18 13:26:13 volumiorpi5 kernel: usb 1-1: USB disconnect, device number 5 Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Starting Shairport Sync Apr 18 13:26:13 volumiorpi5 sudo[5489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 13:26:13 volumiorpi5 sudo[5489]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:13 volumiorpi5 systemd[1]: Starting Music Player Daemon... Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5491, instance 1, run: true Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Apr 18 13:26:13 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Detached Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:13 volumiorpi5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 13:26:13 volumiorpi5 systemd[1]: shairport-sync.service: Succeeded. Apr 18 13:26:13 volumiorpi5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:13 volumiorpi5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 18 13:26:13 volumiorpi5 sudo[5489]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: Shairport-Sync Started Apr 18 13:26:13 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5504, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5511, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5518, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5525, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5532, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5539, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5546, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5553, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5560, instance 1, run: true Apr 18 13:26:14 volumiorpi5 mpd[5480]: Apr 18 13:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 13:26:14 volumiorpi5 systemd[1]: Started Music Player Daemon. Apr 18 13:26:14 volumiorpi5 sudo[5474]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5569, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5576, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5583, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5590, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5597, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5604, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5611, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5618, instance 1, run: true Apr 18 13:26:14 volumiorpi5 kernel: usb 1-1: new high-speed USB device number 6 using xhci-hcd Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5625, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5632, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5639, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5646, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5653, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5660, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5667, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5674, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5681, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5688, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5695, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5702, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5709, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5716, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5723, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5730, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5737, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5744, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5751, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5758, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5765, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5772, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5779, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5786, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5793, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5800, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5807, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5814, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5821, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5828, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5835, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: error: Upnp client error: Error: This socket has been ended by the other party Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5842, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5849, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5856, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5863, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5870, instance 1, run: true Apr 18 13:26:14 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5877, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5884, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5891, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5898, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5905, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5912, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5919, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5926, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5933, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5940, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5947, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5954, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5961, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5968, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5975, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5982, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5989, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 5996, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6003, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6010, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6017, instance 1, run: true Apr 18 13:26:15 volumiorpi5 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.13 Apr 18 13:26:15 volumiorpi5 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 18 13:26:15 volumiorpi5 kernel: usb 1-1: Product: SMSL USB AUDIO Apr 18 13:26:15 volumiorpi5 kernel: usb 1-1: Manufacturer: SMSL Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6024, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6031, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6038, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6045, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6052, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6059, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6066, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6073, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6080, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6087, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6094, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6101, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6108, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6115, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6122, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6129, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6136, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6143, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6150, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6157, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6164, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6171, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6178, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6185, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6192, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6199, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6206, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6213, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6220, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6227, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6234, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6241, instance 1, run: true Apr 18 13:26:15 volumiorpi5 kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6248, instance 1, run: true Apr 18 13:26:15 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6255, instance 1, run: true Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6262, instance 1, run: true Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6269, instance 1, run: true Apr 18 13:26:16 volumiorpi5 kernel: hid-generic 0003:152A:85DD.0006: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-1/input3 Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6279, instance 1, run: true Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Apr 18 13:26:16 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Attached Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::Close All Modals sent Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Device 5 does not have any Mixer Control Available Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Disabling external Volume Control Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Preparing to generate the ALSA configuration file Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Reading ALSA contributions from plugins. Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Asound.conf file unchanged, so no further update is needed Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Output device has changed, restarting MPD Apr 18 13:26:16 volumiorpi5 sudo[6301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 13:26:16 volumiorpi5 sudo[6301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:16 volumiorpi5 sudo[6301]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Output device has changed, restarting Shairport Sync Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:16 volumiorpi5 sudo[6304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 13:26:16 volumiorpi5 sudo[6304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:16 volumiorpi5 systemd[1]: Stopping Music Player Daemon... Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 13:26:16 volumiorpi5 systemd[1]: mpd.service: Succeeded. Apr 18 13:26:16 volumiorpi5 systemd[1]: Stopped Music Player Daemon. Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: MPD Permissions set Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Starting Shairport Sync Apr 18 13:26:16 volumiorpi5 systemd[1]: Starting Music Player Daemon... Apr 18 13:26:16 volumiorpi5 sudo[6315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 13:26:16 volumiorpi5 kernel: usb 1-1: USB disconnect, device number 6 Apr 18 13:26:16 volumiorpi5 sudo[6315]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:16 volumiorpi5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 13:26:16 volumiorpi5 systemd[1]: shairport-sync.service: Succeeded. Apr 18 13:26:16 volumiorpi5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:16 volumiorpi5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:16 volumiorpi5 sudo[6315]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Shairport-Sync Started Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: Apr 18 13:26:16 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Detached Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 18 13:26:16 volumiorpi5 mpd[6309]: Apr 18 13:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 13:26:16 volumiorpi5 systemd[1]: Started Music Player Daemon. Apr 18 13:26:16 volumiorpi5 sudo[6304]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:16 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:16 volumiorpi5 kernel: usb 1-1: new high-speed USB device number 7 using xhci-hcd Apr 18 13:26:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.13 Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: Product: SMSL USB AUDIO Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: Manufacturer: SMSL Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreStateMachine::getcurrentVolume Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Apr 18 13:26:17 volumiorpi5 kernel: usbhid 1-1:1.3: can't add hid device: -71 Apr 18 13:26:17 volumiorpi5 kernel: usbhid: probe of 1-1:1.3 failed with error -71 Apr 18 13:26:17 volumiorpi5 kernel: usb 1-1: USB disconnect, device number 7 Apr 18 13:26:17 volumiorpi5 systemd-udevd[2900]: controlC5: Failed to open ATTR{/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/sound/card5/controlC5/../uevent} for writing: No such file or directory Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: Apr 18 13:26:17 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Attached Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 18 13:26:17 volumiorpi5 volumio[1024]: error: API:pushError: TypeError: Cannot read property 'name' of null Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: Apr 18 13:26:17 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Detached Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 18 13:26:17 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:18 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 18 13:26:18 volumiorpi5 kernel: usb 1-1: new high-speed USB device number 8 using xhci-hcd Apr 18 13:26:18 volumiorpi5 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.13 Apr 18 13:26:18 volumiorpi5 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 18 13:26:18 volumiorpi5 kernel: usb 1-1: Product: SMSL USB AUDIO Apr 18 13:26:18 volumiorpi5 kernel: usb 1-1: Manufacturer: SMSL Apr 18 13:26:19 volumiorpi5 kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Apr 18 13:26:19 volumiorpi5 kernel: hid-generic 0003:152A:85DD.0007: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-1/input3 Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Apr 18 13:26:19 volumiorpi5 volumio[1024]: ---------------------------- USB Audio Device Attached Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::Close All Modals sent Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Device 5 does not have any Mixer Control Available Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Disabling external Volume Control Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Preparing to generate the ALSA configuration file Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Reading ALSA contributions from plugins. Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Asound.conf file unchanged, so no further update is needed Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Output device has changed, restarting MPD Apr 18 13:26:19 volumiorpi5 sudo[6362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 13:26:19 volumiorpi5 sudo[6362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:19 volumiorpi5 sudo[6362]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Output device has changed, restarting Shairport Sync Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:19 volumiorpi5 sudo[6365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 13:26:19 volumiorpi5 sudo[6365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:19 volumiorpi5 systemd[1]: Stopping Music Player Daemon... Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 13:26:19 volumiorpi5 systemd[1]: mpd.service: Succeeded. Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: MPD Permissions set Apr 18 13:26:19 volumiorpi5 systemd[1]: Stopped Music Player Daemon. Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Starting Shairport Sync Apr 18 13:26:19 volumiorpi5 sudo[6376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 13:26:19 volumiorpi5 sudo[6376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:26:19 volumiorpi5 systemd[1]: Starting Music Player Daemon... Apr 18 13:26:19 volumiorpi5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 13:26:19 volumiorpi5 systemd[1]: shairport-sync.service: Succeeded. Apr 18 13:26:19 volumiorpi5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:19 volumiorpi5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 13:26:19 volumiorpi5 sudo[6376]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: Shairport-Sync Started Apr 18 13:26:19 volumiorpi5 mpd[6370]: Apr 18 13:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 13:26:19 volumiorpi5 systemd[1]: Started Music Player Daemon. Apr 18 13:26:19 volumiorpi5 sudo[6365]: pam_unix(sudo:session): session closed for user root Apr 18 13:26:19 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:19 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 18 13:26:19 volumiorpi5 volumio[1024]: error: Upnp client error: Error: This socket has been ended by the other party Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreStateMachine::getcurrentVolume Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:20 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::ClearQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::stPlaybackTimer Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::serviceStop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::serviceStop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ControllerMpd::stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::clearPlayQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::addQueueItems Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::addQueueItems Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPlay Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index undefined Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::startPlaybackTimer Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::clearAddPlayTracks USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 14 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 4 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Apr 18 13:26:21 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand clear took 1 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac" Apr 18 13:26:21 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Apr 18 13:26:21 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ------------------------------ 1ms Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac" took 0 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand play Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ------------------------------ 2ms Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand play took 2 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Apr 18 13:26:21 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Apr 18 13:26:21 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 15 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Plentiful (Originals) (feat. Pusha T)","artist":"Pusha T","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac","trackType":"flac"} Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus stop Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ------------------------------ 18ms Apr 18 13:26:21 volumiorpi5 volumio[1024]: error: FusionDsp - WebSocket error: [object Object] Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1073 Kbps","isStreaming":false,"title":"Plentiful (Originals) (feat. Pusha T)","artist":"Pusha T","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac","trackType":"flac"} Apr 18 13:26:21 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: Received an update from plugin. extracting info from payload Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: ------------------------------ 15ms Apr 18 13:26:21 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6388, instance 1, run: true Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreStateMachine::getcurrentVolume Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::ClearQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::stPlaybackTimer Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::serviceStop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 0 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::serviceStop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::clearPlayQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::addQueueItems Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::addQueueItems Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Preload queue cleared Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/01 - Alicia Keys, Pusha T - Plentiful (Originals) (feat. Pusha T).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/02 - Alicia Keys - Skydive (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/03 - Alicia Keys - Best Of Me (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/05 - Alicia Keys - Is It Insane (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/06 - Alicia Keys - Billions (Originals)(Explicit).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/07 - Alicia Keys - Love When You Call My Name (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/08 - Alicia Keys - Only You (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/09 - Alicia Keys - Daffodils (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/10 - Alicia Keys - Old Memories (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/11 - Alicia Keys - Nat King Cole (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/12 - Alicia Keys, Brandi Carlile - Paper Flowers (Originals) (feat. Brandi Carlile).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/13 - Alicia Keys - Like Water (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/14 - Alicia Keys, Lucky Daye - Stay (feat. Lucky Daye).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Adding Item to queue: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Using cached record of: music-library/USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/15 - Alicia Keys - In Common (Black Coffee Remix).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::saveQueue Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::updateTrackBlock Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrackBlock Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPlay Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::play index undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::startPlaybackTimer Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::clearAddPlayTracks USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Apr 18 13:26:27 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 15 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand stop took 3 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Apr 18 13:26:27 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 2 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand clear took 3 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac" Apr 18 13:26:27 volumiorpi5 volumio[1024]: error: updateQueue error: null Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Apr 18 13:26:27 volumiorpi5 volumio[1024]: ---------------------------- MPD announces system playlist update Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Ignoring MPD Status Update Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ------------------------------ 2ms Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand add "USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac" took 1 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand play Apr 18 13:26:27 volumiorpi5 volumio[1024]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ------------------------------ 5ms Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ------------------------------ 3ms Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand play took 2 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Apr 18 13:26:27 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Apr 18 13:26:27 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 10 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 2 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dead End Road (Originals)","artist":"Alicia Keys","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac","trackType":"flac"} Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus stop Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ------------------------------ 15ms Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dead End Road (Originals)","artist":"Alicia Keys","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac","trackType":"flac"} Apr 18 13:26:27 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService play Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: Received an update from plugin. extracting info from payload Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: ------------------------------ 16ms Apr 18 13:26:27 volumiorpi5 volumio[1024]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::pause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::stPlaybackTimer Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::servicePause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: ControllerMpd::pause Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: Apr 18 13:27:10 volumiorpi5 volumio[1024]: ---------------------------- MPD announces state update: player Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: sendMpdCommand pause took 1 milliseconds Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: ControllerMpd::getState Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseState Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: ControllerMpd::pushState Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreCommandRouter::servicePushState Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44102,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1469 Kbps","isStreaming":false,"title":"Dead End Road (Originals)","artist":"Alicia Keys","album":"KEYS II","uri":"USB/SSD2TBEVO/Music/00 MQA Music/Alicia Keys - KEYS II [2022]/CD1/04 - Alicia Keys - Dead End Road (Originals).flac","trackType":"flac"} Apr 18 13:27:10 volumiorpi5 volumio[1024]: verbose: CURRENT POSITION 3 Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState stateService pause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::pushState Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioPushState Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: CoreStateMachine::stPlaybackTimer Apr 18 13:27:10 volumiorpi5 volumio[1024]: info: ------------------------------ 10ms Apr 18 13:27:11 volumiorpi5 volumio[1024]: info: camilladsp spawned new process with pid 6559, instance 1, run: true Apr 18 13:27:11 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 18 13:27:11 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 18 13:27:13 volumiorpi5 sudo[6574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:27:13 volumiorpi5 sudo[6574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:13 volumiorpi5 sudo[6574]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:13 volumiorpi5 sudo[6576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:27:13 volumiorpi5 sudo[6576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:13 volumiorpi5 sudo[6576]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:13 volumiorpi5 volumio[1024]: verbose: New Socket.io Connection to 192.168.188.159 from 192.168.188.150 UA: okhttp/4.9.2 Total Clients: 9 Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:13 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: Received Get System Info Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: Discovery: Getting this device information Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: Listing playlists Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: Discovery: Getting this device information Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:27:14 volumiorpi5 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: Received Get System Info Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: Discovery: Getting this device information Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:15 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: Received Get System Info Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: Discovery: Getting this device information Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:16 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: Enabling plugin touch_display Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: Loading plugin "touch_display"... Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: Applying required configuration parameters for plugin touch_display Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: PLUGIN START: touch_display Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: Loading i18n strings for locale pl Apr 18 13:27:22 volumiorpi5 volumio[1024]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:27:22 volumiorpi5 sudo[6603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 18 13:27:22 volumiorpi5 sudo[6603]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:22 volumiorpi5 sudo[6606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 18 13:27:22 volumiorpi5 sudo[6606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: touch_display: No backlight interface detected. Apr 18 13:27:22 volumiorpi5 sudo[6603]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:22 volumiorpi5 sudo[6608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 18 13:27:22 volumiorpi5 sudo[6608]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 18 13:27:22 volumiorpi5 systemd[1]: Reloading. Apr 18 13:27:22 volumiorpi5 sudo[6612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 18 13:27:22 volumiorpi5 sudo[6612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:22 volumiorpi5 sudo[6612]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:22 volumiorpi5 volumio[1024]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 18 13:27:22 volumiorpi5 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 18 13:27:23 volumiorpi5 systemd[1]: Reloading. Apr 18 13:27:23 volumiorpi5 sudo[6606]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 18 13:27:23 volumiorpi5 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 18 13:27:23 volumiorpi5 sudo[6608]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: touch_display: systemctl daemon-reload succeeded. Apr 18 13:27:23 volumiorpi5 sudo[6643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 18 13:27:23 volumiorpi5 sudo[6643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:23 volumiorpi5 sudo[6643]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:23 volumiorpi5 volumio[1024]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Apr 18 13:27:23 volumiorpi5 volumio[1024]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: Error: Error Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: Discovery: Getting this device information Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::volumioGetState Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: CorePlayQueue::getTrack 3 Apr 18 13:27:23 volumiorpi5 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:27:30 volumiorpi5 volumio[1024]: info: Starting Uninstall of plugin user_interface - touch_display Apr 18 13:27:30 volumiorpi5 volumio[1024]: info: Uninstalling plugin touch_display Apr 18 13:27:30 volumiorpi5 sudo[6665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 18 13:27:30 volumiorpi5 sudo[6665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:30 volumiorpi5 sudo[6665]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:30 volumiorpi5 sudo[6668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Apr 18 13:27:30 volumiorpi5 sudo[6668]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:30 volumiorpi5 sudo[6671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Apr 18 13:27:30 volumiorpi5 sudo[6671]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:30 volumiorpi5 volumio[1024]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 18 13:27:30 volumiorpi5 volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:27:30 volumiorpi5 volumio[1024]: TypeError: Cannot read property 'split' of undefined Apr 18 13:27:30 volumiorpi5 volumio[1024]: at /data/plugins/user_interface/touch_display/index.js:1193:37 Apr 18 13:27:30 volumiorpi5 volumio[1024]: at ChildProcess.exithandler (child_process.js:299:7) Apr 18 13:27:30 volumiorpi5 volumio[1024]: at ChildProcess.emit (events.js:315:20) Apr 18 13:27:30 volumiorpi5 volumio[1024]: at maybeClose (internal/child_process.js:1048:16) Apr 18 13:27:30 volumiorpi5 volumio[1024]: at Socket. (internal/child_process.js:439:11) Apr 18 13:27:30 volumiorpi5 volumio[1024]: at Socket.emit (events.js:315:20) Apr 18 13:27:30 volumiorpi5 volumio[1024]: at Pipe. (net.js:673:12) Apr 18 13:27:30 volumiorpi5 volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:27:30 volumiorpi5 sudo[6674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Apr 18 13:27:30 volumiorpi5 sudo[6674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:27:30 volumiorpi5 systemd[1]: Started Getty on tty1. Apr 18 13:27:30 volumiorpi5 sudo[6668]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:30 volumiorpi5 systemd[1]: Reloading. Apr 18 13:27:30 volumiorpi5 sudo[6674]: pam_unix(sudo:session): session closed for user root Apr 18 13:27:30 volumiorpi5 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 18 13:27:31 volumiorpi5 sudo[6699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:26 Apr 18 13:27:31 volumiorpi5 sudo[6699]: 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"