-- Logs begin at Fri 2023-10-20 23:23:04 JST, end at Sun 2024-04-21 13:00:54 JST. -- Apr 21 12:59:00 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:00 rivotannoy kernel: retire_capture_urb: 126 callbacks suppressed Apr 21 12:59:01 rivotannoy volumio[5417]: Searching plugin music_service/youtube2 Apr 21 12:59:01 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 12:59:01 rivotannoy volumio[5417]: SearchHeader not found! Apr 21 12:59:01 rivotannoy volumio[5417]: This is a bug, want to help us fix it? Follow the instructions at https://github.com/patrickkfkan/Volumio-YouTube.js/blob/main/docs/updating-the-parser.md or report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues! Apr 21 12:59:01 rivotannoy volumio[5417]: Introspected and JIT generated this class in the meantime: Apr 21 12:59:01 rivotannoy volumio[5417]: class SearchHeader extends YTNode { Apr 21 12:59:01 rivotannoy volumio[5417]: static type = 'SearchHeader'; Apr 21 12:59:01 rivotannoy volumio[5417]: search_filter_button: YTNodes.Button | null; Apr 21 12:59:01 rivotannoy volumio[5417]: constructor(data: RawNode) { Apr 21 12:59:01 rivotannoy volumio[5417]: super(); Apr 21 12:59:01 rivotannoy volumio[5417]: this.search_filter_button = Parser.parseItem(data.searchFilterButton, [ YTNodes.Button ]); Apr 21 12:59:01 rivotannoy volumio[5417]: } Apr 21 12:59:01 rivotannoy volumio[5417]: } Apr 21 12:59:02 rivotannoy volumio[5417]: info: All search sources collected, pushing search results Apr 21 12:59:02 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:03 rivotannoy volumio[5417]: Searching plugin music_service/youtube2 Apr 21 12:59:03 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 12:59:04 rivotannoy volumio[5417]: SearchHeader changed! Apr 21 12:59:04 rivotannoy volumio[5417]: The following keys where altered: chip_bar Apr 21 12:59:04 rivotannoy volumio[5417]: The class has changed to: Apr 21 12:59:04 rivotannoy volumio[5417]: class SearchHeader extends YTNode { Apr 21 12:59:04 rivotannoy volumio[5417]: static type = 'SearchHeader'; Apr 21 12:59:04 rivotannoy volumio[5417]: search_filter_button: YTNodes.Button | null; Apr 21 12:59:04 rivotannoy volumio[5417]: chip_bar?: YTNodes.ChipCloud | null; Apr 21 12:59:04 rivotannoy volumio[5417]: constructor(data: RawNode) { Apr 21 12:59:04 rivotannoy volumio[5417]: super(); Apr 21 12:59:04 rivotannoy volumio[5417]: this.search_filter_button = Parser.parseItem(data.searchFilterButton, [ YTNodes.Button ]); Apr 21 12:59:04 rivotannoy volumio[5417]: this.chip_bar = Reflect.has(data, 'chipBar') ? Parser.parseItem(data.chipBar, [ YTNodes.ChipCloud ]) : undefined; Apr 21 12:59:04 rivotannoy volumio[5417]: } Apr 21 12:59:04 rivotannoy volumio[5417]: } Apr 21 12:59:04 rivotannoy volumio[5417]: info: All search sources collected, pushing search results Apr 21 12:59:04 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:06 rivotannoy volumio[5417]: Searching plugin music_service/youtube2 Apr 21 12:59:06 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 12:59:06 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:06 rivotannoy kernel: retire_capture_urb: 78 callbacks suppressed Apr 21 12:59:07 rivotannoy volumio[5417]: info: All search sources collected, pushing search results Apr 21 12:59:08 rivotannoy volumio[5417]: Searching plugin music_service/youtube2 Apr 21 12:59:08 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 12:59:09 rivotannoy volumio[5417]: info: All search sources collected, pushing search results Apr 21 12:59:09 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:09 rivotannoy volumio[5417]: Searching plugin music_service/youtube2 Apr 21 12:59:09 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 12:59:10 rivotannoy volumio[5417]: info: All search sources collected, pushing search results Apr 21 12:59:11 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:13 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:13 rivotannoy kernel: retire_capture_urb: 127 callbacks suppressed Apr 21 12:59:15 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:17 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:19 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:19 rivotannoy kernel: retire_capture_urb: 46 callbacks suppressed Apr 21 12:59:21 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:24 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:26 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:26 rivotannoy kernel: retire_capture_urb: 175 callbacks suppressed Apr 21 12:59:28 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:30 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:32 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:34 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:34 rivotannoy kernel: retire_capture_urb: 150 callbacks suppressed Apr 21 12:59:36 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:38 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:41 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:41 rivotannoy kernel: retire_capture_urb: 94 callbacks suppressed Apr 21 12:59:43 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:45 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:47 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:47 rivotannoy kernel: retire_capture_urb: 111 callbacks suppressed Apr 21 12:59:49 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:51 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:53 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:53 rivotannoy kernel: retire_capture_urb: 134 callbacks suppressed Apr 21 12:59:56 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 12:59:58 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:00 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:02 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:02 rivotannoy kernel: retire_capture_urb: 134 callbacks suppressed Apr 21 13:00:04 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:06 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:08 rivotannoy audioinput[12657]: /usr/bin/sox WARN alsa: over-run Apr 21 13:00:08 rivotannoy kernel: retire_capture_urb: 142 callbacks suppressed Apr 21 13:00:09 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::ClearQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::serviceStop Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::serviceStop Apr 21 13:00:09 rivotannoy volumio[5417]: info: INPUTS, stopping input: audio_input Apr 21 13:00:09 rivotannoy volumio[5417]: info: Disabiling Audio Input 5 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::clearPlayQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::addQueueItems Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::addQueueItems Apr 21 13:00:09 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:09 rivotannoy volumio[5417]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:09 rivotannoy sudo[12700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Apr 21 13:00:09 rivotannoy sudo[12700]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:00:09 rivotannoy volumio[5417]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D in service youtube2 Apr 21 13:00:09 rivotannoy volumio[5417]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:09 rivotannoy systemd[1]: Stopping Audio Input Service... Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPlay Apr 21 13:00:09 rivotannoy systemd[1]: audioinput.service: Main process exited, code=killed, status=15/TERM Apr 21 13:00:09 rivotannoy volumio[5417]: verbose: UNSET VOLATILE: Service: smart_inputs Apr 21 13:00:09 rivotannoy volumio[5417]: info: Disabiling Audio Input 5 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::play index 0 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:09 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:09 rivotannoy sudo[12705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Apr 21 13:00:09 rivotannoy sudo[12705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:00:09 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::serviceStop Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::serviceStop Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:09 rivotannoy volumio[5417]: info: ControllerMpd::stop Apr 21 13:00:09 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:09 rivotannoy volumio[5417]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 21 13:00:09 rivotannoy volumio[5417]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 21 13:00:09 rivotannoy volumio[5417]: info: sendMpdCommand stop took 52 milliseconds Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::play index undefined Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreStateMachine::startPlaybackTimer Apr 21 13:00:09 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:00:09 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 13:00:09 rivotannoy volumio[5417]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:09 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:09 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:09 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:09 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Apr 21 13:00:09 rivotannoy kernel: spdif_a keep clk continuous Apr 21 13:00:09 rivotannoy kernel: aml_spdif_close Apr 21 13:00:09 rivotannoy kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 21 13:00:09 rivotannoy systemd[1]: audioinput.service: Succeeded. Apr 21 13:00:09 rivotannoy systemd[1]: Stopped Audio Input Service. Apr 21 13:00:09 rivotannoy sudo[12705]: pam_unix(sudo:session): session closed for user root Apr 21 13:00:09 rivotannoy sudo[12700]: pam_unix(sudo:session): session closed for user root Apr 21 13:00:10 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:10 rivotannoy volumio[5417]: info: sendMpdCommand stop took 24 milliseconds Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 13:00:10 rivotannoy volumio[5417]: info: Successfully Stopped Audio Input Apr 21 13:00:10 rivotannoy volumio[5417]: info: Successfully Stopped Audio Input Apr 21 13:00:10 rivotannoy volumio[5417]: info: Apr 21 13:00:10 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:10 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:10 rivotannoy volumio[5417]: info: sendMpdCommand clear took 10 milliseconds Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693609&ei=SY8kZqDNMebU7OsPocad-Ak&ip=157.147.123.226&id=o-AK2Nzvjt-hcZft81y7mhaQ0_mLBwSfi0JmHLOxOCK3el&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a1Nq8uARu_5X07qHybYFWPnrW6hX1KD8JaMpSswXuyFy94eVuVd8n4MQvzaGzXR6sF6g1a9327-&spc=UWF9f-4rlFVn5zLCWa4ZW1L1XPnBA2kb6dSS8NM3bvcgmOuOtA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Ij6WfsYjCM9rwma9NoEwQHgQ&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=ApRDYzrdMJw3FQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhAKgD2tBcy32Qg41ANqr1MylExudT6LIoQrh3KiMEkJZvAiEA4-grGhe5gzUHF-vVDlvAnckHNyct1FjinY08eUvEs44%3D&sig=AJfQdSswRgIhANFOUqkcz5MyGfebPPDw2dLfQuretXTb3krMFVUgdBomAiEA_4dgT9sGxpHt1CV81D1_IwBH1T8RI21A8nugkmfMgxE%3D&cver=2.20230104.01.00&t.YouTube" Apr 21 13:00:10 rivotannoy volumio[5417]: error: updateQueue error: null Apr 21 13:00:10 rivotannoy volumio[5417]: info: Apr 21 13:00:10 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:10 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:10 rivotannoy volumio[5417]: info: ------------------------------ 11ms Apr 21 13:00:10 rivotannoy volumio[5417]: info: sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693609&ei=SY8kZqDNMebU7OsPocad-Ak&ip=157.147.123.226&id=o-AK2Nzvjt-hcZft81y7mhaQ0_mLBwSfi0JmHLOxOCK3el&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a1Nq8uARu_5X07qHybYFWPnrW6hX1KD8JaMpSswXuyFy94eVuVd8n4MQvzaGzXR6sF6g1a9327-&spc=UWF9f-4rlFVn5zLCWa4ZW1L1XPnBA2kb6dSS8NM3bvcgmOuOtA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Ij6WfsYjCM9rwma9NoEwQHgQ&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=ApRDYzrdMJw3FQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhAKgD2tBcy32Qg41ANqr1MylExudT6LIoQrh3KiMEkJZvAiEA4-grGhe5gzUHF-vVDlvAnckHNyct1FjinY08eUvEs44%3D&sig=AJfQdSswRgIhANFOUqkcz5MyGfebPPDw2dLfQuretXTb3krMFVUgdBomAiEA_4dgT9sGxpHt1CV81D1_IwBH1T8RI21A8nugkmfMgxE%3D&cver=2.20230104.01.00&t.YouTube" took 7 milliseconds Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:10 rivotannoy volumio[5417]: info: ------------------------------ 11ms Apr 21 13:00:10 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:10 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand play Apr 21 13:00:10 rivotannoy volumio[5417]: info: Apr 21 13:00:10 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:10 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:10 rivotannoy volumio[5417]: info: sendMpdCommand play took 3 milliseconds Apr 21 13:00:10 rivotannoy volumio[5417]: info: ------------------------------ 24ms Apr 21 13:00:11 rivotannoy volumio[5417]: info: Apr 21 13:00:11 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:11 rivotannoy kernel: aml_spdif_open Apr 21 13:00:11 rivotannoy kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 21 13:00:11 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:11 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:11 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:11 rivotannoy kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Apr 21 13:00:11 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:11 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:11 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:11 rivotannoy kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 21 13:00:11 rivotannoy kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand status took 54 milliseconds Apr 21 13:00:11 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: Apr 21 13:00:11 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693609&ei=SY8kZqDNMebU7OsPocad-Ak&ip=157.147.123.226&id=o-AK2Nzvjt-hcZft81y7mhaQ0_mLBwSfi0JmHLOxOCK3el&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a1Nq8uARu_5X07qHybYFWPnrW6hX1KD8JaMpSswXuyFy94eVuVd8n4MQvzaGzXR6sF6g1a9327-&spc=UWF9f-4rlFVn5zLCWa4ZW1L1XPnBA2kb6dSS8NM3bvcgmOuOtA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Ij6WfsYjCM9rwma9NoEwQHgQ&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=ApRDYzrdMJw3FQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhAKgD2tBcy32Qg41ANqr1MylExudT6LIoQrh3KiMEkJZvAiEA4-grGhe5gzUHF-vVDlvAnckHNyct1FjinY08eUvEs44%3D&sig=AJfQdSswRgIhANFOUqkcz5MyGfebPPDw2dLfQuretXTb3krMFVUgdBomAiEA_4dgT9sGxpHt1CV81D1_IwBH1T8RI21A8nugkmfMgxE%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus stop Apr 21 13:00:11 rivotannoy volumio[5417]: info: ------------------------------ 76ms Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand status took 14 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693609&ei=SY8kZqDNMebU7OsPocad-Ak&ip=157.147.123.226&id=o-AK2Nzvjt-hcZft81y7mhaQ0_mLBwSfi0JmHLOxOCK3el&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a1Nq8uARu_5X07qHybYFWPnrW6hX1KD8JaMpSswXuyFy94eVuVd8n4MQvzaGzXR6sF6g1a9327-&spc=UWF9f-4rlFVn5zLCWa4ZW1L1XPnBA2kb6dSS8NM3bvcgmOuOtA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Ij6WfsYjCM9rwma9NoEwQHgQ&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=ApRDYzrdMJw3FQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhAKgD2tBcy32Qg41ANqr1MylExudT6LIoQrh3KiMEkJZvAiEA4-grGhe5gzUHF-vVDlvAnckHNyct1FjinY08eUvEs44%3D&sig=AJfQdSswRgIhANFOUqkcz5MyGfebPPDw2dLfQuretXTb3krMFVUgdBomAiEA_4dgT9sGxpHt1CV81D1_IwBH1T8RI21A8nugkmfMgxE%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:00:11 rivotannoy volumio[5417]: info: Received an update from plugin. extracting info from payload Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:11 rivotannoy volumio[5417]: info: ------------------------------ 57ms Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::ClearQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::serviceStop Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::serviceStop Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::stop Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::clearPlayQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::addQueueItems Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::addQueueItems Apr 21 13:00:11 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:11 rivotannoy volumio[5417]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:11 rivotannoy volumio[5417]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPlay Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::play index 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::play index undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::startPlaybackTimer Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 13:00:11 rivotannoy volumio[5417]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:11 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Apr 21 13:00:11 rivotannoy kernel: spdif_a keep clk continuous Apr 21 13:00:11 rivotannoy kernel: aml_spdif_close Apr 21 13:00:11 rivotannoy kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Apr 21 13:00:11 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand stop took 89 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand status took 6 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 21 13:00:11 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:11 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:11 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:11 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:11 rivotannoy volumio[5417]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Apr 21 13:00:11 rivotannoy volumio[5417]: info: ------------------------------ 43ms Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:11 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:11 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:12 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand stop took 3 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 13:00:12 rivotannoy volumio[5417]: info: Apr 21 13:00:12 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:12 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand clear took 2 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693611&ei=S48kZqKANJ6z7OsP9Y-92A8&ip=157.147.123.226&id=o-AEY8JnmiaJnlMZpYao4xG_wvTXvDPDodSRjvVmW9VzXA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a39gQRMwRjuDWYt41l5-qZl5aViWHpz_ijXsazRHcw2ENZ_hWRw4_c518PpbOy0BCTF4xdTeIYK&spc=UWF9f0840TJmD9TNd_DMQRhx2gXrN4Xp503FqFbGK-tg2MeonQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=96Pi3FumDjj0rcXtE0bHDq0Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=Lda9QWtBVHTxDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgfISY0KNV3H4zEB2WGBAowhXE6-V-V68THyLcNcfIUIcCIQCA7DxeFFKcbswFdKAdaG74AFbs0c3UFRXjq-HVLfgDjw%3D%3D&sig=AJfQdSswRAIgReWzjUXVziXHQ-TIVH1H_RZ0ee-vKzW2Pah8WX865msCIBkWLIhG7CWSCG3HASnq3FsuCEWNnW9a3iNItUDnMLL6&cver=2.20230104.01.00&t.YouTube" Apr 21 13:00:12 rivotannoy volumio[5417]: error: updateQueue error: null Apr 21 13:00:12 rivotannoy volumio[5417]: info: Apr 21 13:00:12 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:12 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:12 rivotannoy volumio[5417]: info: ------------------------------ 5ms Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693611&ei=S48kZqKANJ6z7OsP9Y-92A8&ip=157.147.123.226&id=o-AEY8JnmiaJnlMZpYao4xG_wvTXvDPDodSRjvVmW9VzXA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a39gQRMwRjuDWYt41l5-qZl5aViWHpz_ijXsazRHcw2ENZ_hWRw4_c518PpbOy0BCTF4xdTeIYK&spc=UWF9f0840TJmD9TNd_DMQRhx2gXrN4Xp503FqFbGK-tg2MeonQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=96Pi3FumDjj0rcXtE0bHDq0Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=Lda9QWtBVHTxDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgfISY0KNV3H4zEB2WGBAowhXE6-V-V68THyLcNcfIUIcCIQCA7DxeFFKcbswFdKAdaG74AFbs0c3UFRXjq-HVLfgDjw%3D%3D&sig=AJfQdSswRAIgReWzjUXVziXHQ-TIVH1H_RZ0ee-vKzW2Pah8WX865msCIBkWLIhG7CWSCG3HASnq3FsuCEWNnW9a3iNItUDnMLL6&cver=2.20230104.01.00&t.YouTube" took 4 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:12 rivotannoy volumio[5417]: info: Apr 21 13:00:12 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:12 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:12 rivotannoy volumio[5417]: info: ------------------------------ 7ms Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand play Apr 21 13:00:12 rivotannoy volumio[5417]: info: ------------------------------ 5ms Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand play took 2 milliseconds Apr 21 13:00:12 rivotannoy kernel: aml_spdif_open Apr 21 13:00:12 rivotannoy kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 21 13:00:12 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:12 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:12 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:12 rivotannoy kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Apr 21 13:00:12 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:12 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:12 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:12 rivotannoy kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 21 13:00:12 rivotannoy kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Apr 21 13:00:12 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Apr 21 13:00:12 rivotannoy volumio[5417]: info: Executing endpoint metavolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: Apr 21 13:00:12 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:12 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:12 rivotannoy volumio[5417]: info: Executing endpoint metavolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: Apr 21 13:00:12 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:12 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand status took 22 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:12 rivotannoy volumio[5417]: info: Executing endpoint metavolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand status took 16 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:12 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693611&ei=S48kZqKANJ6z7OsP9Y-92A8&ip=157.147.123.226&id=o-AEY8JnmiaJnlMZpYao4xG_wvTXvDPDodSRjvVmW9VzXA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a39gQRMwRjuDWYt41l5-qZl5aViWHpz_ijXsazRHcw2ENZ_hWRw4_c518PpbOy0BCTF4xdTeIYK&spc=UWF9f0840TJmD9TNd_DMQRhx2gXrN4Xp503FqFbGK-tg2MeonQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=96Pi3FumDjj0rcXtE0bHDq0Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=Lda9QWtBVHTxDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgfISY0KNV3H4zEB2WGBAowhXE6-V-V68THyLcNcfIUIcCIQCA7DxeFFKcbswFdKAdaG74AFbs0c3UFRXjq-HVLfgDjw%3D%3D&sig=AJfQdSswRAIgReWzjUXVziXHQ-TIVH1H_RZ0ee-vKzW2Pah8WX865msCIBkWLIhG7CWSCG3HASnq3FsuCEWNnW9a3iNItUDnMLL6&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus stop Apr 21 13:00:12 rivotannoy volumio[5417]: info: ------------------------------ 50ms Apr 21 13:00:12 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:12 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693611&ei=S48kZqKANJ6z7OsP9Y-92A8&ip=157.147.123.226&id=o-AEY8JnmiaJnlMZpYao4xG_wvTXvDPDodSRjvVmW9VzXA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pl=19&initcwndbps=1676250&bui=AaUN6a39gQRMwRjuDWYt41l5-qZl5aViWHpz_ijXsazRHcw2ENZ_hWRw4_c518PpbOy0BCTF4xdTeIYK&spc=UWF9f0840TJmD9TNd_DMQRhx2gXrN4Xp503FqFbGK-tg2MeonQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=96Pi3FumDjj0rcXtE0bHDq0Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=Lda9QWtBVHTxDA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgfISY0KNV3H4zEB2WGBAowhXE6-V-V68THyLcNcfIUIcCIQCA7DxeFFKcbswFdKAdaG74AFbs0c3UFRXjq-HVLfgDjw%3D%3D&sig=AJfQdSswRAIgReWzjUXVziXHQ-TIVH1H_RZ0ee-vKzW2Pah8WX865msCIBkWLIhG7CWSCG3HASnq3FsuCEWNnW9a3iNItUDnMLL6&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:12 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:00:12 rivotannoy volumio[5417]: info: Received an update from plugin. extracting info from payload Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:12 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:12 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:12 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:12 rivotannoy volumio[5417]: info: ------------------------------ 62ms Apr 21 13:00:12 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:12 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:12 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:13 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:13 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:13 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:19 rivotannoy volumio[5417]: error: Failed request for metavolumio API Apr 21 13:00:19 rivotannoy volumio[5417]: error: Failed request for metavolumio API Apr 21 13:00:19 rivotannoy volumio[5417]: error: Failed request for metavolumio API Apr 21 13:00:24 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::ClearQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:24 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:24 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::serviceStop Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::serviceStop Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:24 rivotannoy volumio[5417]: info: ControllerMpd::stop Apr 21 13:00:24 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::clearPlayQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::addQueueItems Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::addQueueItems Apr 21 13:00:24 rivotannoy volumio[5417]: info: Preload queue cleared Apr 21 13:00:24 rivotannoy volumio[5417]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:24 rivotannoy volumio[5417]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::saveQueue Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::updateTrackBlock Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrackBlock Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPlay Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::play index 0 Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::stop Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::play index undefined Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreStateMachine::startPlaybackTimer Apr 21 13:00:24 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:00:24 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 13:00:24 rivotannoy volumio[5417]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%5C%22SING%2C%20SING%2C%20SING%5C%22%20BY%20BENNY%20GOODMAN%22%2C%22artist%22%3A%22WorldWar2Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fr2S1I_ien6A%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gSAAuADigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLBK7M6ee_4WOQnoqbv40R8YsaS8EA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22r2S1I_ien6A%22%2C%22params%22%3A%22qgMOU2luZyBzaW5nIHNpbme6AwoIqs7YjKC_7cF5ugMLCO3gzLWhvPuo6QG6AwsI3Oiz4rq5n4G4AboDDxINUkRyMlMxSV9pZW42QboDCwjroo_ox6aqu_4BugMLCPz7rqTZqPvt7wG6AwoIo8Xx4uHhj7kMugMLCPzL_YWp9a-nrAG6AwsI2ICYnaLxgf-6AboDCgj0hdKCk8L08Uy6AwsI-a_qmumOnrHeAboDCwiD08nHscjdu8YBugMKCPDF38m0wtyfH7oDCwj_mN-fuu7Vu48BugMLCPPtv4O8prvSxgG6AwoI1Yzdp42cgtglugMLCLKgyKXG4aqk3QG6AwsIyty57O2snumPAboDCwib3rOtsf_oxbIB8gMFDWmz-D0%253D%22%7D%7D%7D Apr 21 13:00:25 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:25 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:25 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:25 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Apr 21 13:00:25 rivotannoy kernel: audio_ddr_mngr: delay:[0us]; FRDDR_STATUS2: [0x540a3500] [0x540a3600] Apr 21 13:00:25 rivotannoy kernel: spdif_a keep clk continuous Apr 21 13:00:25 rivotannoy kernel: aml_spdif_close Apr 21 13:00:25 rivotannoy kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 21 13:00:25 rivotannoy volumio[5417]: info: Apr 21 13:00:25 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand stop took 121 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand status took 2 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:25 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:25 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:25 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:25 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:25 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:25 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:25 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:25 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:25 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:25 rivotannoy volumio[5417]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Apr 21 13:00:25 rivotannoy volumio[5417]: info: ------------------------------ 35ms Apr 21 13:00:25 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:25 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:25 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:25 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand stop took 2 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 13:00:25 rivotannoy volumio[5417]: info: Apr 21 13:00:25 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:25 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand clear took 4 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube" Apr 21 13:00:25 rivotannoy volumio[5417]: error: updateQueue error: null Apr 21 13:00:25 rivotannoy volumio[5417]: info: Apr 21 13:00:25 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:25 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:25 rivotannoy volumio[5417]: info: ------------------------------ 11ms Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand addid "https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube" took 6 milliseconds Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: MPD COMMAND [object Object] Apr 21 13:00:25 rivotannoy volumio[5417]: info: Apr 21 13:00:25 rivotannoy volumio[5417]: ---------------------------- MPD announces system playlist update Apr 21 13:00:25 rivotannoy volumio[5417]: info: Ignoring MPD Status Update Apr 21 13:00:25 rivotannoy volumio[5417]: info: ------------------------------ 11ms Apr 21 13:00:25 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:25 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand play Apr 21 13:00:25 rivotannoy volumio[5417]: info: ------------------------------ 10ms Apr 21 13:00:25 rivotannoy volumio[5417]: info: sendMpdCommand play took 8 milliseconds Apr 21 13:00:26 rivotannoy volumio[5417]: info: Apr 21 13:00:26 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:26 rivotannoy kernel: aml_spdif_open Apr 21 13:00:26 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:26 rivotannoy kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 21 13:00:26 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:26 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:26 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:26 rivotannoy kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Apr 21 13:00:26 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:26 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:26 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:26 rivotannoy kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 21 13:00:26 rivotannoy kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Apr 21 13:00:26 rivotannoy volumio[5417]: info: Apr 21 13:00:26 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:26 rivotannoy volumio[5417]: info: sendMpdCommand status took 11 milliseconds Apr 21 13:00:26 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:26 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Apr 21 13:00:26 rivotannoy volumio[5417]: info: sendMpdCommand status took 4 milliseconds Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:26 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 21 13:00:26 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:26 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus stop Apr 21 13:00:26 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":662,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:26 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:00:26 rivotannoy volumio[5417]: info: Received an update from plugin. extracting info from payload Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:26 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:26 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:26 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:26 rivotannoy volumio[5417]: info: ------------------------------ 53ms Apr 21 13:00:26 rivotannoy volumio[5417]: info: ------------------------------ 49ms Apr 21 13:00:26 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:26 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:26 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:26 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:26 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:26 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::pause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::servicePause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:31 rivotannoy volumio[5417]: info: ControllerMpd::pause Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand pause Apr 21 13:00:31 rivotannoy volumio[5417]: info: sendMpdCommand pause took 67 milliseconds Apr 21 13:00:31 rivotannoy volumio[5417]: info: Apr 21 13:00:31 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:31 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:31 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Apr 21 13:00:31 rivotannoy kernel: spdif_a keep clk continuous Apr 21 13:00:31 rivotannoy kernel: aml_spdif_close Apr 21 13:00:31 rivotannoy kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:31 rivotannoy volumio[5417]: info: sendMpdCommand status took 2 milliseconds Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:31 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:31 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:31 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6490,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:31 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService pause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus pause Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:31 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:31 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:31 rivotannoy volumio[5417]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:00:31 rivotannoy volumio[5417]: info: ------------------------------ 31ms Apr 21 13:00:31 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:31 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:31 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPlay Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::play index undefined Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:00:37 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::startPlaybackTimer Apr 21 13:00:37 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 13:00:37 rivotannoy volumio[5417]: info: ControllerMpd::resume Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand play Apr 21 13:00:37 rivotannoy kernel: aml_spdif_open Apr 21 13:00:37 rivotannoy kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 21 13:00:37 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:37 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:37 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:37 rivotannoy kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Apr 21 13:00:37 rivotannoy kernel: set normal 512 fs /4 fs Apr 21 13:00:37 rivotannoy kernel: set spdifout clk:6144000, mpll:24576000 Apr 21 13:00:37 rivotannoy kernel: get spdifout clk:6143997, mpll:24575987 Apr 21 13:00:37 rivotannoy kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 21 13:00:37 rivotannoy kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Apr 21 13:00:37 rivotannoy volumio[5417]: info: Apr 21 13:00:37 rivotannoy volumio[5417]: ---------------------------- MPD announces state update: player Apr 21 13:00:37 rivotannoy volumio[5417]: info: sendMpdCommand play took 10 milliseconds Apr 21 13:00:37 rivotannoy volumio[5417]: info: ControllerMpd::getState Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:00:37 rivotannoy kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Apr 21 13:00:37 rivotannoy volumio[5417]: info: sendMpdCommand status took 1 milliseconds Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: ControllerMpd::parseState Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:00:37 rivotannoy volumio[5417]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:00:37 rivotannoy volumio[5417]: info: ControllerMpd::pushState Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::servicePushState Apr 21 13:00:37 rivotannoy volumio[5417]: info: CorePlayQueue::getTrack 0 Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7487,"duration":323,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"\"SING, SING, SING\" BY BENNY GOODMAN","artist":"WorldWar2Music","album":"YouTube","uri":"https://rr3---sn-nvoxu-ioqed.googlevideo.com/videoplayback?expire=1713693625&ei=WY8kZvXuCPG57OsP2Oip2AM&ip=157.147.123.226&id=o-AKrpbFrjpR3CLy1NT2T5HsG5IPzLu6BRaV8Gi4qd1YDl&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=TC&mm=31%2C29&mn=sn-nvoxu-ioqed%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&initcwndbps=1676250&bui=AaUN6a0cmYT9WtQEf9oIZ9YGX-Hm5XpRtWTH7zR4L87dvoSD8tfNJr4pBovi3IrWi629L-anC7ekKX-w&spc=UWF9fy8pYnh1S_46TEB5Seu-4eu4yRyZlV2bJeGOWl2ysG8dIw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-Ve0L4cDp9od8zgF-wBTt34Q&gir=yes&clen=4876485&dur=323.121&lmt=1536902862414572&mt=1713671590&fvip=3&keepalive=yes&c=WEB&sefc=1&n=0CBzLbF4RQeGlw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIgGUgFnA9UPGRu7pAFtgX4g5IBEcIN98-s1al_oNhslKwCIQDUmF7rzZKbbfTrFXvqYXUuOjY4hynLdjh005YLADe2pQ%3D%3D&sig=AJfQdSswRAIgNeILc0Zi97CxD0JG1o0tMwLsZi0PwE6Fjd6YBVYe28UCIEidwdIFlYxgHEeNIVN6w5k7qDWrysefShL1Ghk0adck&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 13:00:37 rivotannoy volumio[5417]: verbose: CURRENT POSITION 0 Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::syncState stateService play Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::syncState currentStatus pause Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreStateMachine::pushState Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:00:37 rivotannoy volumio[5417]: info: CoreCommandRouter::volumioPushState Apr 21 13:00:37 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output update for this device Apr 21 13:00:37 rivotannoy volumio[5417]: info: MRS: Pushing multiroomSync output Apr 21 13:00:37 rivotannoy volumio[5417]: info: ------------------------------ 27ms Apr 21 13:00:37 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:37 rivotannoy volumio[5417]: info: Updating RAAT Signal Path Apr 21 13:00:37 rivotannoy volumio[5417]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 21 13:00:53 rivotannoy volumio[5417]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 21 13:00:53 rivotannoy volumio[5417]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:00:53 rivotannoy volumio[5417]: TypeError: Cannot read property 'then' of undefined Apr 21 13:00:53 rivotannoy volumio[5417]: at outputs.enableAudioOutput (/volumio/app/plugins/audio_interface/outputs/index.js:235:9) Apr 21 13:00:53 rivotannoy volumio[5417]: at CoreCommandRouter.enableAudioOutput (/volumio/app/index.js:2215:30) Apr 21 13:00:53 rivotannoy volumio[5417]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1393:26) Apr 21 13:00:53 rivotannoy volumio[5417]: at Socket.emit (events.js:400:28) Apr 21 13:00:53 rivotannoy volumio[5417]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 21 13:00:53 rivotannoy volumio[5417]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 21 13:00:53 rivotannoy volumio[5417]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:00:54 rivotannoy sudo[12770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 12:59 Apr 21 13:00:54 rivotannoy sudo[12770]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:23:03 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="0e426cfb4e8f6eeaa137fb8c97a2672d"