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