-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-11-27 08:00:40 CET. -- Nov 27 07:59:03 volumio volumio[999]: STREAMING PROXY: Client dropped request, destroying Nov 27 07:59:05 volumio volumio[999]: info: Preload queue cleared Nov 27 07:59:05 volumio volumio[999]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::ClearQueue Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::stop Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::clearPlayQueue Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::saveQueue Nov 27 07:59:05 volumio volumio[999]: info: CoreCommandRouter::volumioPushQueue Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::addQueueItems Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::addQueueItems Nov 27 07:59:05 volumio volumio[999]: info: Preload queue cleared Nov 27 07:59:05 volumio volumio[999]: info: Adding Item to queue: qobuz://playlist/1791667 Nov 27 07:59:05 volumio volumio[999]: info: Using cached record of: qobuz://playlist/1791667 Nov 27 07:59:05 volumio volumio[999]: info: CoreCommandRouter::volumioPushQueue Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::saveQueue Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::updateTrackBlock Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::getTrackBlock Nov 27 07:59:05 volumio volumio[999]: info: CoreCommandRouter::volumioPlay Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::play index 0 Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::stop Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::play index undefined Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:05 volumio volumio[999]: info: CoreStateMachine::startPlaybackTimer Nov 27 07:59:05 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:05 volumio volumio[999]: info: [1732690745792] ControllerQobuz::clearAddPlayTrack Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 07:59:05 volumio volumio[999]: info: Nov 27 07:59:05 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:05 volumio volumio[999]: info: sendMpdCommand stop took 15 milliseconds Nov 27 07:59:05 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Nov 27 07:59:05 volumio volumio[999]: info: Nov 27 07:59:05 volumio volumio[999]: ---------------------------- MPD announces system playlist update Nov 27 07:59:05 volumio volumio[999]: info: Ignoring MPD Status Update Nov 27 07:59:05 volumio volumio[999]: info: sendMpdCommand status took 2 milliseconds Nov 27 07:59:05 volumio volumio[999]: info: sendMpdCommand clear took 1 milliseconds Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:05 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/6732347" Nov 27 07:59:05 volumio volumio[999]: error: updateQueue error: null Nov 27 07:59:05 volumio volumio[999]: info: ------------------------------ 3ms Nov 27 07:59:05 volumio volumio[999]: STREAMING PROXY: Handling url /?data=qobuz://song/6732347 Nov 27 07:59:05 volumio volumio[999]: info: Executing endpoint getStreamUrlqobuz Nov 27 07:59:05 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 27 07:59:06 volumio volumio[999]: info: getStreamUrl took 651 milliseconds Nov 27 07:59:06 volumio volumio[999]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=6732347&fmt=7&profile=raw&app_id=539451548&cid=2437301&etsp=1732694346&hmac=jb6SoHFc7TS80XuNYrTH01L4Vq0 Nov 27 07:59:06 volumio volumio[999]: STREAMING PROXY: Response: 200, length: 65038176 Nov 27 07:59:06 volumio volumio[999]: STREAMING PROXY: Client dropped request, destroying Nov 27 07:59:06 volumio volumio[999]: info: sendMpdCommand playlistinfo took 710 milliseconds Nov 27 07:59:06 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6732347" Nov 27 07:59:06 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:06 volumio volumio[999]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 27 07:59:06 volumio volumio[999]: info: ------------------------------ 716ms Nov 27 07:59:06 volumio volumio[999]: info: Nov 27 07:59:06 volumio volumio[999]: ---------------------------- MPD announces system playlist update Nov 27 07:59:06 volumio volumio[999]: info: Ignoring MPD Status Update Nov 27 07:59:06 volumio volumio[999]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6732347" took 4 milliseconds Nov 27 07:59:06 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 27 07:59:06 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand play Nov 27 07:59:06 volumio volumio[999]: info: ------------------------------ 2ms Nov 27 07:59:06 volumio volumio[999]: info: sendMpdCommand play took 1 milliseconds Nov 27 07:59:06 volumio volumio[999]: STREAMING PROXY: Handling url /?data=qobuz://song/6732347 Nov 27 07:59:06 volumio volumio[999]: info: Executing endpoint getStreamUrlqobuz Nov 27 07:59:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 27 07:59:07 volumio volumio[999]: info: getStreamUrl took 728 milliseconds Nov 27 07:59:07 volumio volumio[999]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=6732347&fmt=7&profile=raw&app_id=539451548&cid=2437301&etsp=1732694347&hmac=yZorrQN59YcLEeWo-xWxza-3Tio Nov 27 07:59:07 volumio volumio[999]: STREAMING PROXY: Response: 200, length: 65038176 Nov 27 07:59:07 volumio volumio[999]: info: Nov 27 07:59:07 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:07 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:07 volumio volumio[999]: info: Nov 27 07:59:07 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:07 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand status took 13 milliseconds Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand status took 15 milliseconds Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand clearerror took 9 milliseconds Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand clearerror took 6 milliseconds Nov 27 07:59:07 volumio volumio[999]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:07 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:07 volumio volumio[999]: info: ControllerMpd::pushState Nov 27 07:59:07 volumio volumio[999]: info: CoreCommandRouter::servicePushState Nov 27 07:59:07 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:07 volumio volumio[999]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":174,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6732347","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/6732347","trackType":"qobuz"} Nov 27 07:59:07 volumio volumio[999]: verbose: CURRENT POSITION 0 Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::syncState stateService pause Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus stop Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::pushState Nov 27 07:59:07 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 07:59:07 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Nov 27 07:59:07 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Nov 27 07:59:07 volumio volumio[999]: info: MRS: Pushing multiroomSync output Nov 27 07:59:07 volumio volumio[999]: info: ControllerMpd::pushState Nov 27 07:59:07 volumio volumio[999]: info: CoreCommandRouter::servicePushState Nov 27 07:59:07 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:07 volumio volumio[999]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":174,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6732347","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/6732347","trackType":"qobuz"} Nov 27 07:59:07 volumio volumio[999]: verbose: CURRENT POSITION 0 Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::syncState stateService pause Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus stop Nov 27 07:59:07 volumio volumio[999]: info: CoreStateMachine::pushState Nov 27 07:59:07 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Nov 27 07:59:07 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Nov 27 07:59:07 volumio volumio[999]: info: MRS: Pushing multiroomSync output Nov 27 07:59:07 volumio volumio[999]: info: ------------------------------ 81ms Nov 27 07:59:07 volumio volumio[999]: info: ------------------------------ 75ms Nov 27 07:59:07 volumio volumio[999]: info: [ASDebug] CurState: PrevState: undefined Nov 27 07:59:07 volumio volumio[999]: info: [ASDebug] Togle GPIO: ON Nov 27 07:59:07 volumio volumio[999]: info: [ASDebug] CurState: undefined PrevState: Nov 27 07:59:07 volumio volumio[999]: info: [ASDebug] Togle GPIO: ON Nov 27 07:59:07 volumio volumio[999]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zqSiYmtdpyflKYXLml3mlV4Hu0b2.9ca9391ab4c2223b446d9940b5ae182d.state.status' Nov 27 07:59:11 volumio volumio[999]: STREAMING PROXY: Client dropped request, destroying Nov 27 07:59:13 volumio volumio[999]: info: CoreCommandRouter::volumioPlay Nov 27 07:59:13 volumio volumio[999]: info: CoreStateMachine::play index undefined Nov 27 07:59:13 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 07:59:13 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:13 volumio volumio[999]: info: CoreStateMachine::startPlaybackTimer Nov 27 07:59:13 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:13 volumio volumio[999]: info: [1732690753185] ControllerQobuz::clearAddPlayTrack Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 07:59:13 volumio volumio[999]: info: Nov 27 07:59:13 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand stop took 9 milliseconds Nov 27 07:59:13 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clear Nov 27 07:59:13 volumio volumio[999]: info: Nov 27 07:59:13 volumio volumio[999]: ---------------------------- MPD announces system playlist update Nov 27 07:59:13 volumio volumio[999]: info: Ignoring MPD Status Update Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand status took 1 milliseconds Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand clear took 1 milliseconds Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/6732347" Nov 27 07:59:13 volumio volumio[999]: error: updateQueue error: null Nov 27 07:59:13 volumio volumio[999]: info: ------------------------------ 3ms Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:13 volumio volumio[999]: STREAMING PROXY: Handling url /?data=qobuz://song/6732347 Nov 27 07:59:13 volumio volumio[999]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 27 07:59:13 volumio volumio[999]: info: ------------------------------ 5ms Nov 27 07:59:13 volumio volumio[999]: info: Executing endpoint getStreamUrlqobuz Nov 27 07:59:13 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 27 07:59:13 volumio volumio[999]: info: getStreamUrl took 653 milliseconds Nov 27 07:59:13 volumio volumio[999]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=6732347&fmt=7&profile=raw&app_id=539451548&cid=2437301&etsp=1732694353&hmac=Afz5mwErysej7-z2E2wz_dJleBU Nov 27 07:59:13 volumio volumio[999]: STREAMING PROXY: Response: 200, length: 65038176 Nov 27 07:59:13 volumio volumio[999]: STREAMING PROXY: Client dropped request, destroying Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6732347" Nov 27 07:59:13 volumio volumio[999]: info: Nov 27 07:59:13 volumio volumio[999]: ---------------------------- MPD announces system playlist update Nov 27 07:59:13 volumio volumio[999]: info: Ignoring MPD Status Update Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6732347" took 1 milliseconds Nov 27 07:59:13 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 27 07:59:13 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand play Nov 27 07:59:13 volumio volumio[999]: info: ------------------------------ 2ms Nov 27 07:59:13 volumio volumio[999]: info: sendMpdCommand play took 1 milliseconds Nov 27 07:59:13 volumio volumio[999]: STREAMING PROXY: Handling url /?data=qobuz://song/6732347 Nov 27 07:59:13 volumio volumio[999]: info: Executing endpoint getStreamUrlqobuz Nov 27 07:59:13 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 27 07:59:14 volumio volumio[999]: info: getStreamUrl took 661 milliseconds Nov 27 07:59:14 volumio volumio[999]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1291004&eid=6732347&fmt=7&profile=raw&app_id=539451548&cid=2437301&etsp=1732694354&hmac=7D-5UjR7iuukBak3lO11MrV-Jvo Nov 27 07:59:14 volumio volumio[999]: STREAMING PROXY: Response: 200, length: 65038176 Nov 27 07:59:14 volumio volumio[999]: info: Nov 27 07:59:14 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:14 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:14 volumio volumio[999]: info: Nov 27 07:59:14 volumio volumio[999]: ---------------------------- MPD announces state update: player Nov 27 07:59:14 volumio volumio[999]: info: ControllerMpd::getState Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand status took 7 milliseconds Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand status took 11 milliseconds Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand clearerror took 6 milliseconds Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::parseState Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:14 volumio volumio[999]: info: ControllerMpd::pushState Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::servicePushState Nov 27 07:59:14 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:14 volumio volumio[999]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":174,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6732347","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/6732347","trackType":"qobuz"} Nov 27 07:59:14 volumio volumio[999]: verbose: CURRENT POSITION 0 Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::syncState stateService pause Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus stop Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::pushState Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Nov 27 07:59:14 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Nov 27 07:59:14 volumio volumio[999]: info: MRS: Pushing multiroomSync output Nov 27 07:59:14 volumio volumio[999]: info: ------------------------------ 47ms Nov 27 07:59:14 volumio volumio[999]: info: [ASDebug] CurState: PrevState: undefined Nov 27 07:59:14 volumio volumio[999]: info: [ASDebug] Togle GPIO: ON Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand clearerror took 42 milliseconds Nov 27 07:59:14 volumio volumio[999]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 27 07:59:14 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo Nov 27 07:59:14 volumio volumio[999]: info: ControllerMpd::pushState Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::servicePushState Nov 27 07:59:14 volumio volumio[999]: info: CorePlayQueue::getTrack 0 Nov 27 07:59:14 volumio volumio[999]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":174,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6732347","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/6732347","trackType":"qobuz"} Nov 27 07:59:14 volumio volumio[999]: verbose: CURRENT POSITION 0 Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::syncState stateService pause Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus stop Nov 27 07:59:14 volumio volumio[999]: info: CoreStateMachine::pushState Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 07:59:14 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Nov 27 07:59:14 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Nov 27 07:59:14 volumio volumio[999]: info: MRS: Pushing multiroomSync output Nov 27 07:59:14 volumio volumio[999]: info: ------------------------------ 80ms Nov 27 07:59:14 volumio volumio[999]: info: [ASDebug] CurState: PrevState: Nov 27 07:59:14 volumio volumio[999]: info: VolumeController::SetAlsaVolume81 Nov 27 07:59:15 volumio volumio[999]: info: CoreStateMachine::pushState Nov 27 07:59:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 07:59:15 volumio volumio[999]: info: CoreCommandRouter::volumioPushState Nov 27 07:59:15 volumio volumio[999]: info: MRS: Pushing multiroomSync output update for this device Nov 27 07:59:15 volumio volumio[999]: info: MRS: Pushing multiroomSync output Nov 27 07:59:15 volumio volumio[999]: info: [ASDebug] CurState: PrevState: Nov 27 07:59:15 volumio volumio[999]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 27 07:59:19 volumio volumio[999]: STREAMING PROXY: Client dropped request, destroying Nov 27 07:59:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 07:59:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 07:59:27 volumio volumio[999]: info: Discovery: Getting this device information Nov 27 07:59:27 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 07:59:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 08:00:15 volumio volumio[999]: info: Discovery: Getting this device information Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 08:00:15 volumio volumio[999]: info: Discovery: Getting this device information Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:15 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 08:00:16 volumio volumio[999]: verbose: New Socket.io Connection to 192.168.0.41:3000 from 192.168.0.39 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Nov 27 08:00:17 volumio volumio[999]: verbose: New Socket.io Connection to 192.168.0.41 from 192.168.0.39 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::volumioGetVisibleSources Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 27 08:00:17 volumio volumio[999]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 27 08:00:17 volumio volumio[999]: info: Received Get System Info Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 08:00:17 volumio volumio[999]: info: Discovery: Getting this device information Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:17 volumio volumio[999]: info: Listing playlists Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::volumioGetQueue Nov 27 08:00:17 volumio volumio[999]: info: CoreStateMachine::getQueue Nov 27 08:00:17 volumio volumio[999]: info: CorePlayQueue::getQueue Nov 27 08:00:17 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 08:00:27 volumio volumio[999]: info: Discovery: Getting this device information Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::volumioGetState Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 27 08:00:27 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 27 08:00:32 volumio volumio[999]: info: Starting Uninstall of plugin music_service - podcast Nov 27 08:00:32 volumio volumio[999]: info: Uninstalling plugin podcast Nov 27 08:00:32 volumio volumio[999]: info: Disabling plugin podcast Nov 27 08:00:32 volumio volumio[999]: info: Checking if uninstall.sh is present Nov 27 08:00:32 volumio volumio[999]: info: Executing uninstall.sh Nov 27 08:00:32 volumio sudo[4902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/podcast/uninstall.sh Nov 27 08:00:32 volumio sudo[4902]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 08:00:32 volumio sudo[4902]: pam_unix(sudo:session): session closed for user root Nov 27 08:00:32 volumio volumio[999]: info: Uninstall script completed Nov 27 08:00:32 volumio volumio[999]: info: Removing plugin podcast from configuration Nov 27 08:00:33 volumio volumio[999]: info: Successfully removed podcast configuration files Nov 27 08:00:33 volumio volumio[999]: info: Plugin folders cleanup Nov 27 08:00:33 volumio volumio[999]: info: Scanning into folder /volumio/app/plugins/ Nov 27 08:00:33 volumio volumio[999]: info: Scanning category audio_interface Nov 27 08:00:33 volumio volumio[999]: info: Scanning category miscellanea Nov 27 08:00:33 volumio volumio[999]: info: Scanning category music_service Nov 27 08:00:33 volumio volumio[999]: info: Scanning category plugins.json Nov 27 08:00:33 volumio volumio[999]: info: Scanning category system_controller Nov 27 08:00:33 volumio volumio[999]: info: Scanning category user_interface Nov 27 08:00:33 volumio volumio[999]: info: Scanning into folder /data/plugins/ Nov 27 08:00:33 volumio volumio[999]: info: Scanning category music_service Nov 27 08:00:33 volumio volumio[999]: info: Cleaning folder for podcast Nov 27 08:00:33 volumio volumio[999]: info: Scanning category system_controller Nov 27 08:00:33 volumio volumio[999]: info: Plugin folders cleanup completed Nov 27 08:00:33 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 27 08:00:33 volumio volumio[999]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 27 08:00:33 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 27 08:00:37 volumio volumio[999]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 27 08:00:37 volumio volumio[999]: info: Uninstalling plugin ampswitch Nov 27 08:00:37 volumio volumio[999]: info: [ASDebug] Port: 24 Nov 27 08:00:37 volumio volumio[999]: info: [ASDebug] Inverted: false Nov 27 08:00:37 volumio volumio[999]: info: [ASDebug] Delay: 720 Nov 27 08:00:40 volumio volumio[999]: info: [ASDebug] Port: 24 Nov 27 08:00:40 volumio volumio[999]: info: [ASDebug] Inverted: false Nov 27 08:00:40 volumio volumio[999]: info: [ASDebug] Delay: 720 Nov 27 08:00:40 volumio volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 08:00:40 volumio volumio[999]: Error: EBADF: bad file descriptor, close Nov 27 08:00:40 volumio volumio[999]: at Object.closeSync (fs.js:439:3) Nov 27 08:00:40 volumio volumio[999]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 27 08:00:40 volumio volumio[999]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 27 08:00:40 volumio volumio[999]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 27 08:00:40 volumio volumio[999]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 27 08:00:40 volumio volumio[999]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 27 08:00:40 volumio volumio[999]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 27 08:00:40 volumio volumio[999]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 27 08:00:40 volumio volumio[999]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1551:29) Nov 27 08:00:40 volumio volumio[999]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Nov 27 08:00:40 volumio volumio[999]: at Socket.emit (events.js:315:20) Nov 27 08:00:40 volumio volumio[999]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 27 08:00:40 volumio volumio[999]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 27 08:00:40 volumio volumio[999]: errno: -9, Nov 27 08:00:40 volumio volumio[999]: syscall: 'close', Nov 27 08:00:40 volumio volumio[999]: code: 'EBADF' Nov 27 08:00:40 volumio volumio[999]: } Nov 27 08:00:40 volumio volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 08:00:40 volumio sudo[4973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 07:59 Nov 27 08:00:40 volumio sudo[4973]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"