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