-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Thu 2024-11-21 21:57:41 MSK. -- Nov 21 21:56:55 volumio volumio[926]: info: CoreCommandRouter::volumioGetQueue Nov 21 21:56:55 volumio volumio[926]: info: CoreStateMachine::getQueue Nov 21 21:56:55 volumio volumio[926]: info: CorePlayQueue::getQueue Nov 21 21:56:56 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 21:56:56 volumio volumio[926]: info: Preload queue cleared Nov 21 21:56:59 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 21:56:59 volumio volumio[926]: info: Getting BBC Radios Nov 21 21:57:00 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 21:57:00 volumio volumio[926]: info: Getting BBC Radios Nov 21 21:57:01 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 21 21:57:01 volumio volumio[926]: info: Getting BBC Radios Nov 21 21:57:01 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:01 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::ClearQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::stPlaybackTimer Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::serviceStop Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::serviceStop Nov 21 21:57:02 volumio volumio[926]: info: [1732215422712] ControllerWebradio::stop Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::clearPlayQueue Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::saveQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::addQueueItems Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::addQueueItems Nov 21 21:57:02 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8 Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::saveQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPlay Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::play index 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::play index undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::startPlaybackTimer Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: [1732215422722] ControllerWebradio::clearAddPlayTrack Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand stop took 16 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand stop took 6 milliseconds Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand clear took 2 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand status took 1 milliseconds Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 8ms Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: error: updateQueue error: null Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 9ms Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" took 9 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 2ms Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand play Nov 21 21:57:02 volumio volumio[926]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 15ms Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 7ms Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand play took 5 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 4ms Nov 21 21:57:02 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::ClearQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::clearPlayQueue Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::saveQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::addQueueItems Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::addQueueItems Nov 21 21:57:02 volumio volumio[926]: info: Preload queue cleared Nov 21 21:57:02 volumio volumio[926]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8 Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::saveQueue Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPlay Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::play index 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::play index undefined Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::startPlaybackTimer Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: info: [1732215422955] ControllerWebradio::clearAddPlayTrack Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand stop took 4 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand status took 1 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand clear took 1 milliseconds Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:02 volumio volumio[926]: error: updateQueue error: null Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 3ms Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" took 3 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand play Nov 21 21:57:02 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:02 volumio volumio[926]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 21:57:02 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:02 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:02 volumio volumio[926]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 21:57:02 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::syncState stateService stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:02 volumio volumio[926]: info: No code Nov 21 21:57:02 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:02 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 13ms Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 15ms Nov 21 21:57:02 volumio volumio[926]: info: Nov 21 21:57:02 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:02 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 15ms Nov 21 21:57:02 volumio volumio[926]: info: sendMpdCommand play took 14 milliseconds Nov 21 21:57:02 volumio volumio[926]: info: ------------------------------ 2ms Nov 21 21:57:03 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 21 21:57:03 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 21 21:57:03 volumio volumio[926]: info: camilladsp spawned new process with pid 15700, instance 1, run: true Nov 21 21:57:03 volumio volumio[926]: info: camilladsp spawned new process with pid 15707, instance 2, run: true Nov 21 21:57:03 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 21 21:57:03 volumio volumio[926]: info: camilladsp spawned new process with pid 15714, instance 3, run: true Nov 21 21:57:03 volumio volumio[926]: info: Nov 21 21:57:03 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:03 volumio volumio[926]: info: Nov 21 21:57:03 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - ---- read samplerate from file: 48000 Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 21 21:57:03 volumio volumio[926]: info: Nov 21 21:57:03 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand status took 41 milliseconds Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand status took 41 milliseconds Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:03 volumio volumio[926]: info: Nov 21 21:57:03 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand status took 6 milliseconds Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand status took 5 milliseconds Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:03 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:03 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play Nov 21 21:57:03 volumio volumio[926]: info: Received an update from plugin. extracting info from payload Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: ------------------------------ 67ms Nov 21 21:57:03 volumio volumio[926]: info: ------------------------------ 71ms Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 21 21:57:03 volumio volumio[926]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:03 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2236,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:03 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play Nov 21 21:57:03 volumio volumio[926]: info: Received an update from plugin. extracting info from payload Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2236,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:03 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play Nov 21 21:57:03 volumio volumio[926]: info: Received an update from plugin. extracting info from payload Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:03 volumio volumio[926]: info: ------------------------------ 57ms Nov 21 21:57:03 volumio volumio[926]: info: ------------------------------ 54ms Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:03 volumio volumio[926]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 21 21:57:10 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Nov 21 21:57:12 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 21:57:12 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 21 21:57:12 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 21:57:19 volumio volumio[926]: info: FusionDsp - Stopping FusionDsp service Nov 21 21:57:19 volumio volumio[926]: info: camilladsp stopping service pid 15714... Nov 21 21:57:19 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 21 21:57:19 volumio volumio[926]: info: camilladsp service terminated, instance 3 Nov 21 21:57:19 volumio volumio[926]: info: Disabling plugin fusiondsp Nov 21 21:57:19 volumio volumio[926]: info: Preparing to generate the ALSA configuration file Nov 21 21:57:19 volumio sudo[15766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Nov 21 21:57:19 volumio sudo[15766]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:19 volumio systemd[1]: Stopping FusionDsp Daemon... Nov 21 21:57:19 volumio volumio[14185]: Backend configuration: Nov 21 21:57:19 volumio volumio[14185]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 21 21:57:19 volumio volumio[14185]: active_config_txt: null Nov 21 21:57:19 volumio volumio[14185]: camilla_host: 127.0.0.1 Nov 21 21:57:19 volumio volumio[14185]: camilla_port: 9876 Nov 21 21:57:19 volumio volumio[14185]: coeff_dir: /data/INTERNAL/FusionDsp/filters Nov 21 21:57:19 volumio volumio[14185]: config_dir: /data/configuration/audio_interface/fusiondsp Nov 21 21:57:19 volumio volumio[14185]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 21 21:57:19 volumio volumio[14185]: log_file: /tmp/camilladsp.log Nov 21 21:57:19 volumio volumio[14185]: on_get_active_config: null Nov 21 21:57:19 volumio volumio[14185]: on_set_active_config: null Nov 21 21:57:19 volumio volumio[14185]: port: 5011 Nov 21 21:57:19 volumio volumio[14185]: supported_capture_types: null Nov 21 21:57:19 volumio volumio[14185]: supported_playback_types: null Nov 21 21:57:19 volumio volumio[14185]: update_config_symlink: false Nov 21 21:57:19 volumio volumio[14185]: update_config_txt: false Nov 21 21:57:19 volumio volumio[14185]: ======== Running on http://0.0.0.0:5011 ======== Nov 21 21:57:19 volumio volumio[14185]: (Press CTRL+C to quit) Nov 21 21:57:19 volumio volumio[926]: info: Asound.conf file written Nov 21 21:57:19 volumio sudo[15769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 21 21:57:19 volumio sudo[15769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:19 volumio sudo[15769]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:19 volumio volumio[926]: info: Output device has changed, restarting MPD Nov 21 21:57:19 volumio systemd[1]: fusiondsp.service: Succeeded. Nov 21 21:57:19 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 21 21:57:19 volumio sudo[15766]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:19 volumio sudo[15774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 21:57:19 volumio sudo[15774]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:19 volumio sudo[15774]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:19 volumio volumio[926]: info: Output device has changed, restarting Shairport Sync Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 21:57:19 volumio sudo[15777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 21:57:19 volumio sudo[15777]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:19 volumio systemd[1]: Stopping Music Player Daemon... Nov 21 21:57:19 volumio volumio[926]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 21:57:19 volumio volumio[926]: info: Done. Nov 21 21:57:19 volumio volumio[926]: info: MPD Permissions set Nov 21 21:57:19 volumio volumio[926]: info: FusionDsp - Reporting Fusion DSP Disabled Nov 21 21:57:19 volumio volumio[926]: info: Removing fusiondspeq DSP Signal Path Element Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:19 volumio volumio[926]: info: Starting Shairport Sync Nov 21 21:57:19 volumio sudo[15787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 21:57:19 volumio sudo[15787]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 21:57:19 volumio sudo[15787]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:19 volumio volumio[926]: info: Shairport-Sync Started Nov 21 21:57:19 volumio volumio[926]: (node:926) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 21 21:57:19 volumio volumio[926]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 21 21:57:19 volumio volumio[926]: (node:926) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 21 21:57:19 volumio volumio[926]: (node:926) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 21 21:57:19 volumio volumio[926]: (node:926) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 21 21:57:19 volumio volumio[926]: (node:926) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 21 21:57:26 volumio systemd[1]: mpd.service: Succeeded. Nov 21 21:57:26 volumio systemd[1]: Stopped Music Player Daemon. Nov 21 21:57:26 volumio systemd[1]: Starting Music Player Daemon... Nov 21 21:57:26 volumio sudo[15806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15808, instance 1, run: true Nov 21 21:57:26 volumio sudo[15806]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:26 volumio sudo[15806]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15819, instance 1, run: true Nov 21 21:57:26 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15826, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15833, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15840, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15847, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15854, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15861, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15868, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15872, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15882, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15889, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15896, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15903, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15910, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15917, instance 1, run: true Nov 21 21:57:26 volumio volumio[926]: info: camilladsp spawned new process with pid 15924, instance 2, run: true Nov 21 21:57:26 volumio volumio[926]: info: CoreCommandRouter::volumioNext Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::next Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::stPlaybackTimer Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Nov 21 21:57:26 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:26 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:26 volumio volumio[926]: info: CoreStateMachine::serviceStop Nov 21 21:57:26 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:26 volumio volumio[926]: info: CoreCommandRouter::serviceStop Nov 21 21:57:26 volumio volumio[926]: info: [1732215446997] ControllerWebradio::stop Nov 21 21:57:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:27 volumio volumio[926]: error: MPD error: Error: This socket has been ended by the other party Nov 21 21:57:27 volumio volumio[926]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 21 21:57:27 volumio volumio[926]: error: MPD error: Error: This socket has been ended by the other party Nov 21 21:57:27 volumio volumio[926]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 21 21:57:27 volumio volumio[926]: error: MPD error: Error: This socket has been ended by the other party Nov 21 21:57:27 volumio volumio[926]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15931, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15938, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15945, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15952, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15959, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15966, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15973, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15980, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15987, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 15994, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16001, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16008, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16015, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16022, instance 2, run: true Nov 21 21:57:27 volumio mpd[15818]: Nov 21 21:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 21:57:27 volumio systemd[1]: Started Music Player Daemon. Nov 21 21:57:27 volumio sudo[15777]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16029, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16039, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: error: updateQueue error: null Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16046, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16053, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16060, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16067, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16074, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16081, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16088, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16095, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16102, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16109, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16116, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16123, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16130, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16137, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16144, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16151, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16158, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16165, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16172, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16179, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16186, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16193, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16200, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16207, instance 2, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16214, instance 1, run: true Nov 21 21:57:27 volumio volumio[926]: info: camilladsp spawned new process with pid 16221, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16228, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16235, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16242, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16249, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16256, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16263, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16270, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16277, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16284, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16291, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16298, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16305, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16312, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16319, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16326, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16333, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: CoreCommandRouter::volumioPlay Nov 21 21:57:28 volumio volumio[926]: info: CoreStateMachine::play index undefined Nov 21 21:57:28 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:28 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:28 volumio volumio[926]: info: CoreStateMachine::startPlaybackTimer Nov 21 21:57:28 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:28 volumio volumio[926]: info: [1732215448352] ControllerWebradio::clearAddPlayTrack Nov 21 21:57:28 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:28 volumio volumio[926]: info: sendMpdCommand stop took 2 milliseconds Nov 21 21:57:28 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 21:57:28 volumio volumio[926]: info: Nov 21 21:57:28 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:28 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:28 volumio volumio[926]: info: sendMpdCommand clear took 2 milliseconds Nov 21 21:57:28 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16340, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: error: updateQueue error: null Nov 21 21:57:28 volumio volumio[926]: info: Nov 21 21:57:28 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:28 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:28 volumio volumio[926]: info: ------------------------------ 25ms Nov 21 21:57:28 volumio volumio[926]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" took 24 milliseconds Nov 21 21:57:28 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 21:57:28 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand play Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16350, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: ------------------------------ 23ms Nov 21 21:57:28 volumio volumio[926]: info: sendMpdCommand play took 27 milliseconds Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16357, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16364, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16371, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16378, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16385, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16392, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16399, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16406, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16413, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16420, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16427, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16434, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16441, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16448, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16455, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16462, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16469, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16476, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16483, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16490, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16497, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16504, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16511, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16518, instance 2, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16525, instance 1, run: true Nov 21 21:57:28 volumio volumio[926]: info: camilladsp spawned new process with pid 16532, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16539, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16543, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16553, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16560, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16567, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16574, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16581, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16588, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16595, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16602, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16609, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16616, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16623, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16630, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16637, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16645, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16652, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16659, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16666, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16673, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16680, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16687, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: Nov 21 21:57:29 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:29 volumio volumio[926]: info: Nov 21 21:57:29 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand status took 4 milliseconds Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:29 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop Nov 21 21:57:29 volumio volumio[926]: info: ------------------------------ 10ms Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16695, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand playlistinfo took 28 milliseconds Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:29 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play Nov 21 21:57:29 volumio volumio[926]: info: Received an update from plugin. extracting info from payload Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:29 volumio volumio[926]: info: ------------------------------ 49ms Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16702, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16709, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16716, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16723, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16730, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16737, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16744, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16751, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16758, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16765, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16772, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16779, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::volumioStop Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::stop Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::stPlaybackTimer Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::serviceStop Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::serviceStop Nov 21 21:57:29 volumio volumio[926]: info: [1732215449835] ControllerWebradio::stop Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:29 volumio volumio[926]: info: Nov 21 21:57:29 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand stop took 9 milliseconds Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16786, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 21 21:57:29 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:29 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:29 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:29 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:29 volumio volumio[926]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 21 21:57:29 volumio volumio[926]: info: ------------------------------ 28ms Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16793, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16800, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16807, instance 1, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16814, instance 2, run: true Nov 21 21:57:29 volumio volumio[926]: info: camilladsp spawned new process with pid 16821, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16828, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16835, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16842, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16849, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16856, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16863, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16870, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16877, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16884, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16891, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16898, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16905, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16912, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16919, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16926, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16933, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16940, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16947, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16954, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16961, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16968, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16975, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16982, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::volumioPlay Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::play index undefined Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 21:57:30 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::startPlaybackTimer Nov 21 21:57:30 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:30 volumio volumio[926]: info: [1732215450497] ControllerWebradio::clearAddPlayTrack Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand stop took 1 milliseconds Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 21:57:30 volumio volumio[926]: info: Nov 21 21:57:30 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:30 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand clear took 2 milliseconds Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16989, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: error: updateQueue error: null Nov 21 21:57:30 volumio volumio[926]: info: Nov 21 21:57:30 volumio volumio[926]: ---------------------------- MPD announces system playlist update Nov 21 21:57:30 volumio volumio[926]: info: Ignoring MPD Status Update Nov 21 21:57:30 volumio volumio[926]: info: ------------------------------ 22ms Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8" took 20 milliseconds Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand play Nov 21 21:57:30 volumio volumio[926]: info: ------------------------------ 5ms Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand play took 2 milliseconds Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 16996, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17003, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17010, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17018, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17025, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17032, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17039, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17046, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17053, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17060, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: Nov 21 21:57:30 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:30 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17067, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: Nov 21 21:57:30 volumio volumio[926]: ---------------------------- MPD announces state update: player Nov 21 21:57:30 volumio volumio[926]: info: ControllerMpd::getState Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand status took 25 milliseconds Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand status took 3 milliseconds Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::parseState Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:30 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:30 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:30 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:30 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop Nov 21 21:57:30 volumio volumio[926]: info: ------------------------------ 34ms Nov 21 21:57:30 volumio volumio[926]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 21 21:57:30 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo Nov 21 21:57:30 volumio volumio[926]: info: ControllerMpd::pushState Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::servicePushState Nov 21 21:57:30 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:30 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d96000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d96000.norewind.m3u8","trackType":"m3u8"} Nov 21 21:57:30 volumio volumio[926]: verbose: CURRENT POSITION 0 Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::syncState stateService play Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play Nov 21 21:57:30 volumio volumio[926]: info: Received an update from plugin. extracting info from payload Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:30 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:30 volumio volumio[926]: info: CoreStateMachine::pushState Nov 21 21:57:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Nov 21 21:57:30 volumio volumio[926]: info: ------------------------------ 33ms Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17074, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17081, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17088, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17095, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17102, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17109, instance 2, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17116, instance 1, run: true Nov 21 21:57:30 volumio volumio[926]: info: camilladsp spawned new process with pid 17123, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17130, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17137, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17144, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17151, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17158, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17165, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17172, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17179, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17186, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17193, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17200, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17207, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17214, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17221, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17228, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17235, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17242, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17249, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17256, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17263, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17270, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17277, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17284, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17291, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17298, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17305, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17312, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17319, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17326, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17333, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17340, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17347, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17354, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17361, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17368, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17375, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17382, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17389, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17396, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17403, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17410, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17417, instance 2, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17424, instance 1, run: true Nov 21 21:57:31 volumio volumio[926]: info: camilladsp spawned new process with pid 17431, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17438, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17445, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17452, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17459, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17466, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17473, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17480, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17487, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17494, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17501, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17508, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17515, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17522, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17529, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17536, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17543, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17550, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17557, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17564, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17571, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17578, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17585, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17592, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17599, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17606, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17613, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17620, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17627, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17634, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17641, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17648, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17655, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17662, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17669, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17676, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17683, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17690, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17697, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17704, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17711, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17718, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17725, instance 2, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17732, instance 1, run: true Nov 21 21:57:32 volumio volumio[926]: info: camilladsp spawned new process with pid 17739, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17746, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17753, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17760, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17767, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17774, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17781, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17788, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17795, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17802, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17809, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17816, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17823, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17830, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17837, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17844, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17851, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17858, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17865, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17872, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17879, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17886, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17893, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17900, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17907, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17914, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17921, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17928, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17935, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17942, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17949, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17956, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17963, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17970, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17977, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17984, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17991, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 17998, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18005, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18012, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18019, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18026, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18033, instance 2, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18040, instance 1, run: true Nov 21 21:57:33 volumio volumio[926]: info: camilladsp spawned new process with pid 18047, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18054, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18061, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18068, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18075, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18082, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18089, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18096, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18103, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18110, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18117, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18124, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18131, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18138, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18145, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18152, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18159, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18166, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18173, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18180, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18187, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18194, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18201, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18208, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18215, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18222, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18229, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18249, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18257, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18264, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18271, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18278, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18285, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18292, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18299, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18306, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18313, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18320, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18327, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18334, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18341, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18348, instance 1, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18355, instance 2, run: true Nov 21 21:57:34 volumio volumio[926]: info: camilladsp spawned new process with pid 18362, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18369, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18376, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18383, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18390, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18397, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18404, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18411, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18418, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18425, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18432, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18439, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18446, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18453, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18460, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18467, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18474, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18481, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18488, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18495, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18502, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18509, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18516, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18523, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18530, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18537, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18544, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18551, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18558, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18565, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18572, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18579, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18586, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18593, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18600, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18607, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18614, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18621, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18628, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18635, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18642, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18649, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18656, instance 1, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18663, instance 2, run: true Nov 21 21:57:35 volumio volumio[926]: info: camilladsp spawned new process with pid 18670, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18677, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18684, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18691, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18698, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18705, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18712, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18719, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18726, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18733, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18740, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18747, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18754, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18761, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18768, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18775, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18782, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18789, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18796, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18803, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18810, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18817, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18824, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18831, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18838, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18845, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18852, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18859, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18866, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18873, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18880, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18887, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18894, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18901, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18908, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18915, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18922, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18929, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18937, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18944, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18951, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18958, instance 2, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18965, instance 1, run: true Nov 21 21:57:36 volumio volumio[926]: info: camilladsp spawned new process with pid 18972, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 18979, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 18986, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 18993, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19000, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19007, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19013, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19021, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19028, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19035, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19042, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19049, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19056, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19063, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19070, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19077, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19084, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19091, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19098, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19107, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19115, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19122, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19129, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19136, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19143, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19150, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19157, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19164, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19171, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19178, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19185, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19192, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19199, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19206, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19213, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19220, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19227, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19234, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19241, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19248, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19255, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19262, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19269, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19276, instance 1, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19283, instance 2, run: true Nov 21 21:57:37 volumio volumio[926]: info: camilladsp spawned new process with pid 19290, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19297, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19304, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19311, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19318, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19325, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19332, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19339, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19346, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19353, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19360, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19367, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19374, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19381, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19388, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19395, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19402, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19409, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19416, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19423, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19430, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19437, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19444, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19451, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19458, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19465, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19472, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19479, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19486, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19493, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19500, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19507, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19514, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19521, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19528, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19535, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19542, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19549, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19556, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19563, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19570, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19577, instance 2, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19584, instance 1, run: true Nov 21 21:57:38 volumio volumio[926]: info: camilladsp spawned new process with pid 19591, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19598, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19605, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19612, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19619, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19626, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19633, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19640, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19648, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19655, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19662, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19669, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19676, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19683, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19690, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19697, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19704, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19711, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19718, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19725, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19732, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19739, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19746, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19753, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19760, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19767, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19774, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19781, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19788, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19795, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19802, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19809, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19816, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19823, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19830, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19837, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19844, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19851, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19858, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19865, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19872, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19879, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19886, instance 2, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19893, instance 1, run: true Nov 21 21:57:39 volumio volumio[926]: info: camilladsp spawned new process with pid 19900, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19907, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19913, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19921, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19928, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19935, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19942, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19949, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19956, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19963, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19970, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19977, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19984, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19991, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 19998, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20005, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20012, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20019, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20026, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20033, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20040, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20047, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20054, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20061, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20068, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20075, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20082, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20089, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20096, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: Starting Uninstall of plugin audio_interface - fusiondsp Nov 21 21:57:40 volumio volumio[926]: info: Uninstalling plugin fusiondsp Nov 21 21:57:40 volumio volumio[926]: info: Disabling plugin fusiondsp Nov 21 21:57:40 volumio volumio[926]: info: Preparing to generate the ALSA configuration file Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20103, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: Asound.conf file unchanged, so no further update is needed Nov 21 21:57:40 volumio volumio[926]: info: Output device has changed, restarting MPD Nov 21 21:57:40 volumio sudo[20111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 21:57:40 volumio sudo[20111]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:40 volumio volumio[926]: info: Output device has changed, restarting Shairport Sync Nov 21 21:57:40 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:40 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 21:57:40 volumio sudo[20111]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:40 volumio sudo[20114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 21:57:40 volumio sudo[20114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:40 volumio systemd[1]: Stopping Music Player Daemon... Nov 21 21:57:40 volumio volumio[926]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 21:57:40 volumio volumio[926]: info: Checking if uninstall.sh is present Nov 21 21:57:40 volumio systemd[1]: mpd.service: Succeeded. Nov 21 21:57:40 volumio systemd[1]: Stopped Music Player Daemon. Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20119, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: Executing uninstall.sh Nov 21 21:57:40 volumio volumio[926]: info: MPD Permissions set Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20133, instance 1, run: true Nov 21 21:57:40 volumio systemd[1]: Starting Music Player Daemon... Nov 21 21:57:40 volumio sudo[20129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Nov 21 21:57:40 volumio sudo[20129]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20140, instance 2, run: true Nov 21 21:57:40 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:40 volumio sudo[20120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 21:57:40 volumio sudo[20120]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:40 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 21:57:40 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 21:57:40 volumio sudo[20151]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Nov 21 21:57:40 volumio sudo[20120]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:40 volumio sudo[20151]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 21:57:40 volumio sudo[20151]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:40 volumio sudo[20129]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:40 volumio volumio[926]: info: camilladsp spawned new process with pid 20158, instance 1, run: true Nov 21 21:57:40 volumio volumio[926]: info: Uninstall script completed Nov 21 21:57:40 volumio volumio[926]: info: Removing plugin fusiondsp from configuration Nov 21 21:57:41 volumio volumio[926]: info: Successfully removed fusiondsp configuration files Nov 21 21:57:41 volumio volumio[926]: info: Plugin folders cleanup Nov 21 21:57:41 volumio volumio[926]: info: Scanning into folder /volumio/app/plugins/ Nov 21 21:57:41 volumio volumio[926]: info: Scanning category audio_interface Nov 21 21:57:41 volumio volumio[926]: info: Scanning category miscellanea Nov 21 21:57:41 volumio volumio[926]: info: Scanning category music_service Nov 21 21:57:41 volumio volumio[926]: info: Scanning category plugins.json Nov 21 21:57:41 volumio volumio[926]: info: Scanning category system_controller Nov 21 21:57:41 volumio volumio[926]: info: Scanning category user_interface Nov 21 21:57:41 volumio volumio[926]: info: Scanning into folder /data/plugins/ Nov 21 21:57:41 volumio volumio[926]: info: Scanning category audio_interface Nov 21 21:57:41 volumio volumio[926]: info: Cleaning folder for fusiondsp Nov 21 21:57:41 volumio volumio[926]: info: Scanning category system_controller Nov 21 21:57:41 volumio volumio[926]: info: Scanning category user_interface Nov 21 21:57:41 volumio volumio[926]: info: Plugin folders cleanup completed Nov 21 21:57:41 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 21:57:41 volumio volumio[926]: info: [{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.3","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":false,"active":false}] Nov 21 21:57:41 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 21 21:57:41 volumio volumio[926]: info: camilladsp spawned new process with pid undefined, instance 2, run: true Nov 21 21:57:41 volumio volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 21:57:41 volumio volumio[926]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Nov 21 21:57:41 volumio volumio[926]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 21 21:57:41 volumio volumio[926]: at onErrorNT (internal/child_process.js:465:16) Nov 21 21:57:41 volumio volumio[926]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 21 21:57:41 volumio volumio[926]: errno: -2, Nov 21 21:57:41 volumio volumio[926]: code: 'ENOENT', Nov 21 21:57:41 volumio volumio[926]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 21 21:57:41 volumio volumio[926]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 21 21:57:41 volumio volumio[926]: spawnargs: [ Nov 21 21:57:41 volumio volumio[926]: '-p', Nov 21 21:57:41 volumio volumio[926]: 9876, Nov 21 21:57:41 volumio volumio[926]: '-o', Nov 21 21:57:41 volumio volumio[926]: '/tmp/camilladsp.log', Nov 21 21:57:41 volumio volumio[926]: '-l', Nov 21 21:57:41 volumio volumio[926]: 'warn', Nov 21 21:57:41 volumio volumio[926]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 21 21:57:41 volumio volumio[926]: ] Nov 21 21:57:41 volumio volumio[926]: } Nov 21 21:57:41 volumio volumio[926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 21:57:41 volumio mpd[20157]: Nov 21 21:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 21:57:41 volumio systemd[1]: Started Music Player Daemon. Nov 21 21:57:41 volumio sudo[20114]: pam_unix(sudo:session): session closed for user root Nov 21 21:57:41 volumio sudo[20179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 21:56 Nov 21 21:57:41 volumio sudo[20179]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"