-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Tue 2024-07-09 10:26:21 EDT. -- Jul 09 10:25:32 volumio-moonpi ntpd[768]: 15.204.87.223 local addr 10.0.1.111 -> Jul 09 10:25:33 volumio-moonpi ntpd[768]: 12.167.151.1 local addr 10.0.1.111 -> Jul 09 10:25:38 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: lastfm , handleBrowseUri Jul 09 10:25:38 volumio-moonpi volumio[952]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Jul 09 10:25:38 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioGetQueue Jul 09 10:25:38 volumio-moonpi volumio[952]: info: CoreStateMachine::getQueue Jul 09 10:25:38 volumio-moonpi volumio[952]: info: CorePlayQueue::getQueue Jul 09 10:25:38 volumio-moonpi volumio[952]: info: Listing playlists Jul 09 10:25:46 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Jul 09 10:25:46 volumio-moonpi volumio[952]: info: Preload queue cleared Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Preload queue cleared Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::ClearQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::stop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::stPlaybackTimer Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::updateTrackBlock Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrackBlock Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::pushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::serviceStop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::serviceStop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: [1720535148382] ControllerTidal::stop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ControllerMpd::stop Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::clearPlayQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::saveQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPushQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::addQueueItems Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::addQueueItems Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Preload queue cleared Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Adding Item to queue: webrp/0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Exploding uri webrp/0 in service radio_paradise Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPushQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::saveQueue Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::updateTrackBlock Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrackBlock Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPlay Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::play index 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::stop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::play index undefined Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::startPlaybackTimer Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand stop took 32 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand stop took 5 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand clear took 87 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:48 volumio-moonpi volumio[952]: error: updateQueue error: null Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: options Jul 09 10:25:48 volumio-moonpi volumio[952]: error: updateQueue error: null Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 26ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand consume 1 took 17 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 17ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: [1720535148531] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: options Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand status took 209 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 206 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand status took 203 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand play Jul 09 10:25:48 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::pushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreStateMachine::pushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPushState Jul 09 10:25:48 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 304ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 297ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Jul 09 10:25:48 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 108ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: sendMpdCommand play took 62 milliseconds Jul 09 10:25:48 volumio-moonpi volumio[952]: info: ------------------------------ 13ms Jul 09 10:25:48 volumio-moonpi volumio[952]: info: [1720535148848] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Jul 09 10:25:48 volumio-moonpi volumio[952]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 09 10:25:49 volumio-moonpi volumio[952]: info: [1720535149397] [RadioParadise] received new metadata: {"time":73,"artist":"Shivkumar Sharma","title":"Ananda","album":"Fusion India","year":"2003","cover":"https://img.radioparadise.com/covers/l/B00008PRPA.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00008PRPA.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00008PRPA.jpg"} Jul 09 10:25:49 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:49 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:49 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:49 volumio-moonpi volumio[952]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00008PRPA.jpg","uri":"https://stream.radioparadise.com/flac","name":"Ananda","title":"Ananda","artist":"Shivkumar Sharma","album":"Fusion India","streaming":true,"disableUiControls":true,"duration":78,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 09 10:25:49 volumio-moonpi volumio[952]: verbose: CURRENT POSITION 0 Jul 09 10:25:49 volumio-moonpi volumio[952]: info: CoreStateMachine::syncState stateService play Jul 09 10:25:49 volumio-moonpi volumio[952]: info: CoreStateMachine::syncState currentStatus stop Jul 09 10:25:49 volumio-moonpi volumio[952]: info: [1720535149418] [RadioParadise] setting new timer with duration of 78000 seconds. Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate, raw: Jul 09 10:25:50 volumio-moonpi volumio[952]: error: FusionDsp - invalid sample rate Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate, raw: Jul 09 10:25:50 volumio-moonpi volumio[952]: error: FusionDsp - invalid sample rate Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 09 10:25:50 volumio-moonpi volumio[952]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: camilladsp stopping service pid 2355... Jul 09 10:25:50 volumio-moonpi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: camilladsp service terminated, instance 2 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 10:25:50 volumio-moonpi volumio[952]: info: camilladsp service started and running in background, instance 2 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 215 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 214 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3637, instance 2, run: true Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 102 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 110ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 102 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 104 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 100 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 371ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 367ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces system playlist update Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Ignoring MPD Status Update Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Jul 09 10:25:50 volumio-moonpi volumio[952]: ---------------------------- MPD announces state update: player Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::getState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand status Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 18ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand status took 13 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseState Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 203ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 198ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 176ms Jul 09 10:25:50 volumio-moonpi volumio[952]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 09 10:25:50 volumio-moonpi volumio[952]: verbose: ControllerMpd::parseTrackInfo Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ControllerMpd::pushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CoreCommandRouter::servicePushState Jul 09 10:25:50 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:25:50 volumio-moonpi volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 09 10:25:50 volumio-moonpi volumio[952]: info: ------------------------------ 49ms Jul 09 10:25:51 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:51 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:25:51 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:25:51 volumio-moonpi volumio[952]: 64000 bytes (64 kB, 62 KiB) copied, 0.000912809 s, 70.1 MB/s Jul 09 10:25:51 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3642, instance 2, run: true Jul 09 10:25:53 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:53 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:25:53 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:25:53 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000821663 s, 79.8 MB/s Jul 09 10:25:53 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3647, instance 2, run: true Jul 09 10:25:54 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:54 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:25:54 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:25:54 volumio-moonpi volumio[952]: 61712 bytes (62 kB, 60 KiB) copied, 0.000872861 s, 70.7 MB/s Jul 09 10:25:54 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3652, instance 2, run: true Jul 09 10:25:55 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:55 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:25:55 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:25:55 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000684268 s, 95.8 MB/s Jul 09 10:25:55 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3657, instance 2, run: true Jul 09 10:25:56 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:56 volumio-moonpi volumio[952]: 132+40 records in Jul 09 10:25:56 volumio-moonpi volumio[952]: 132+40 records out Jul 09 10:25:56 volumio-moonpi volumio[952]: 4511280 bytes (4.5 MB, 4.3 MiB) copied, 0.0304789 s, 148 MB/s Jul 09 10:25:56 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3662, instance 2, run: true Jul 09 10:25:57 volumio-moonpi volumio[952]: info: MRS: Updating multiroomSync output Jul 09 10:25:57 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:57 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:25:57 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:25:57 volumio-moonpi volumio[952]: 64576 bytes (65 kB, 63 KiB) copied, 0.00068057 s, 94.9 MB/s Jul 09 10:25:57 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3667, instance 2, run: true Jul 09 10:25:58 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:58 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:25:58 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:25:58 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000643383 s, 102 MB/s Jul 09 10:25:58 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3672, instance 2, run: true Jul 09 10:25:59 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:25:59 volumio-moonpi volumio[952]: 59+1 records in Jul 09 10:25:59 volumio-moonpi volumio[952]: 59+1 records out Jul 09 10:25:59 volumio-moonpi volumio[952]: 1959408 bytes (2.0 MB, 1.9 MiB) copied, 0.0130834 s, 150 MB/s Jul 09 10:25:59 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3677, instance 2, run: true Jul 09 10:26:00 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:00 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:26:00 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:26:00 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000672236 s, 97.5 MB/s Jul 09 10:26:00 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3682, instance 2, run: true Jul 09 10:26:01 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:01 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:01 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:01 volumio-moonpi volumio[952]: 62032 bytes (62 kB, 61 KiB) copied, 0.000646352 s, 96.0 MB/s Jul 09 10:26:01 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3687, instance 2, run: true Jul 09 10:26:02 volumio-moonpi volumio[952]: info: CoreCommandRouter::volumioPlay Jul 09 10:26:02 volumio-moonpi volumio[952]: info: CoreStateMachine::play index undefined Jul 09 10:26:02 volumio-moonpi volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 10:26:02 volumio-moonpi volumio[952]: info: CorePlayQueue::getTrack 0 Jul 09 10:26:03 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:03 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:03 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:03 volumio-moonpi volumio[952]: 65216 bytes (65 kB, 64 KiB) copied, 0.000657914 s, 99.1 MB/s Jul 09 10:26:03 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3692, instance 2, run: true Jul 09 10:26:04 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:04 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:04 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:04 volumio-moonpi volumio[952]: 62976 bytes (63 kB, 62 KiB) copied, 0.000688226 s, 91.5 MB/s Jul 09 10:26:04 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3697, instance 2, run: true Jul 09 10:26:05 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:05 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:26:05 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:26:05 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000671299 s, 97.6 MB/s Jul 09 10:26:05 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3702, instance 2, run: true Jul 09 10:26:06 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:06 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:06 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:06 volumio-moonpi volumio[952]: 62656 bytes (63 kB, 61 KiB) copied, 0.000638904 s, 98.1 MB/s Jul 09 10:26:06 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3707, instance 2, run: true Jul 09 10:26:07 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:07 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:26:07 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:26:07 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000665518 s, 98.5 MB/s Jul 09 10:26:07 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3712, instance 2, run: true Jul 09 10:26:08 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:08 volumio-moonpi volumio[952]: 31+1 records in Jul 09 10:26:08 volumio-moonpi volumio[952]: 31+1 records out Jul 09 10:26:08 volumio-moonpi volumio[952]: 1047728 bytes (1.0 MB, 1023 KiB) copied, 0.0157476 s, 66.5 MB/s Jul 09 10:26:08 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3717, instance 2, run: true Jul 09 10:26:09 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:09 volumio-moonpi volumio[952]: 65+1 records in Jul 09 10:26:09 volumio-moonpi volumio[952]: 65+1 records out Jul 09 10:26:09 volumio-moonpi volumio[952]: 2161856 bytes (2.2 MB, 2.1 MiB) copied, 0.0303674 s, 71.2 MB/s Jul 09 10:26:09 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3724, instance 2, run: true Jul 09 10:26:10 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:10 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:10 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:10 volumio-moonpi volumio[952]: 61712 bytes (62 kB, 60 KiB) copied, 0.000369634 s, 167 MB/s Jul 09 10:26:10 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3729, instance 2, run: true Jul 09 10:26:11 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:11 volumio-moonpi volumio[952]: 13+1 records in Jul 09 10:26:11 volumio-moonpi volumio[952]: 13+1 records out Jul 09 10:26:11 volumio-moonpi volumio[952]: 455200 bytes (455 kB, 445 KiB) copied, 0.00735153 s, 61.9 MB/s Jul 09 10:26:11 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3734, instance 2, run: true Jul 09 10:26:13 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:13 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:13 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:13 volumio-moonpi volumio[952]: 61712 bytes (62 kB, 60 KiB) copied, 0.00136078 s, 45.4 MB/s Jul 09 10:26:13 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3755, instance 2, run: true Jul 09 10:26:14 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:14 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:26:14 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:26:14 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000361405 s, 181 MB/s Jul 09 10:26:14 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3797, instance 2, run: true Jul 09 10:26:15 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:15 volumio-moonpi volumio[952]: 2+0 records in Jul 09 10:26:15 volumio-moonpi volumio[952]: 2+0 records out Jul 09 10:26:15 volumio-moonpi volumio[952]: 65536 bytes (66 kB, 64 KiB) copied, 0.000351613 s, 186 MB/s Jul 09 10:26:15 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3802, instance 2, run: true Jul 09 10:26:16 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:16 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:16 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:16 volumio-moonpi volumio[952]: 61712 bytes (62 kB, 60 KiB) copied, 0.000479998 s, 129 MB/s Jul 09 10:26:16 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3807, instance 2, run: true Jul 09 10:26:17 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:17 volumio-moonpi volumio[952]: 1+1 records in Jul 09 10:26:17 volumio-moonpi volumio[952]: 1+1 records out Jul 09 10:26:17 volumio-moonpi volumio[952]: 62592 bytes (63 kB, 61 KiB) copied, 0.000959058 s, 65.3 MB/s Jul 09 10:26:17 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3812, instance 2, run: true Jul 09 10:26:18 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:18 volumio-moonpi volumio[952]: 62+1 records in Jul 09 10:26:18 volumio-moonpi volumio[952]: 62+1 records out Jul 09 10:26:18 volumio-moonpi volumio[952]: 2058304 bytes (2.1 MB, 2.0 MiB) copied, 0.0300757 s, 68.4 MB/s Jul 09 10:26:18 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3817, instance 2, run: true Jul 09 10:26:19 volumio-moonpi volumio[952]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 09 10:26:19 volumio-moonpi volumio[952]: 12+1 records in Jul 09 10:26:19 volumio-moonpi volumio[952]: 12+1 records out Jul 09 10:26:19 volumio-moonpi volumio[952]: 422432 bytes (422 kB, 413 KiB) copied, 0.0044754 s, 94.4 MB/s Jul 09 10:26:19 volumio-moonpi volumio[952]: info: camilladsp spawned new process with pid 3822, instance 2, run: true Jul 09 10:26:19 volumio-moonpi volumio[952]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPause Jul 09 10:26:19 volumio-moonpi volumio[952]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPause] in plugin multiroom Jul 09 10:26:19 volumio-moonpi volumio[952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 10:26:19 volumio-moonpi volumio[952]: TypeError: Cannot read property 'then' of undefined Jul 09 10:26:19 volumio-moonpi volumio[952]: at outputs.audioOutputPause (/volumio/app/plugins/audio_interface/outputs/index.js:417:9) Jul 09 10:26:19 volumio-moonpi volumio[952]: at CoreCommandRouter.audioOutputPause (/volumio/app/index.js:2271:30) Jul 09 10:26:19 volumio-moonpi volumio[952]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1441:26) Jul 09 10:26:19 volumio-moonpi volumio[952]: at Socket.emit (events.js:315:20) Jul 09 10:26:19 volumio-moonpi volumio[952]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 09 10:26:19 volumio-moonpi volumio[952]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 09 10:26:19 volumio-moonpi volumio[952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 10:26:21 volumio-moonpi sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 10:25 Jul 09 10:26:21 volumio-moonpi sudo[3836]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"