-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-29 22:32:25 CEST. -- Jun 29 22:31:01 volumio volumio[5551]: info: Stopping peppymeterbasic service Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::stop Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::stPlaybackTimer Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::updateTrackBlock Jun 29 22:31:01 volumio volumio[5551]: info: CorePlayQueue::getTrackBlock Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:01 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::serviceStop Jun 29 22:31:01 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::serviceStop Jun 29 22:31:01 volumio volumio[5551]: info: [1751229061790] ControllerTidal::stop Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 29 22:31:01 volumio volumio[5551]: info: ControllerMpd::stop Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 22:31:01 volumio volumio[5551]: STREAMING PROXY: Client dropped request, destroying Jun 29 22:31:01 volumio volumio[5551]: info: Disabling plugin peppymeterbasic Jun 29 22:31:01 volumio volumio[5551]: info: Preparing to generate the ALSA configuration file Jun 29 22:31:01 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:31:01 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:31:01 volumio volumio[5551]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 29 22:31:01 volumio volumio[5551]: info: Jun 29 22:31:01 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:31:01 volumio volumio[5551]: info: sendMpdCommand stop took 83 milliseconds Jun 29 22:31:01 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:31:01 volumio sudo[9383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 29 22:31:01 volumio sudo[9383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:01 volumio volumio[5551]: info: Jun 29 22:31:01 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:31:01 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:31:01 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 29 22:31:01 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 29 22:31:01 volumio volumio[5551]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 29 22:31:01 volumio volumio[5551]: info: Reading ALSA contributions from plugins. Jun 29 22:31:01 volumio sudo[9386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 29 22:31:01 volumio sudo[9386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:01 volumio volumio[5551]: info: sendMpdCommand status took 65 milliseconds Jun 29 22:31:01 volumio volumio[5551]: info: sendMpdCommand status took 33 milliseconds Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:31:01 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 29 22:31:01 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 29 22:31:01 volumio volumio[5551]: info: sendMpdCommand playlistinfo took 54 milliseconds Jun 29 22:31:01 volumio volumio[5551]: info: sendMpdCommand playlistinfo took 54 milliseconds Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:31:01 volumio volumio[5551]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:31:01 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:31:01 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:31:01 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio sudo[9386]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio sudo[9383]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:02 volumio volumio[5551]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"322955705&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/322955705&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:31:02 volumio volumio[5551]: verbose: CURRENT POSITION 3 Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::syncState stateService stop Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus stop Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio volumio[5551]: info: No code Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:02 volumio volumio[5551]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"322955705&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/322955705&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:31:02 volumio volumio[5551]: verbose: CURRENT POSITION 3 Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::syncState stateService stop Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus stop Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio volumio[5551]: info: No code Jun 29 22:31:02 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:02 volumio volumio[5551]: info: ------------------------------ 159ms Jun 29 22:31:02 volumio volumio[5551]: info: ------------------------------ 129ms Jun 29 22:31:02 volumio volumio[5551]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 29 22:31:02 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:02 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:02 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:02 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:02 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:02 volumio volumio[5551]: info: peppymeterbasic Daemon Stop Jun 29 22:31:02 volumio volumio[5551]: info: Asound.conf file written Jun 29 22:31:02 volumio sudo[9402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 29 22:31:02 volumio sudo[9402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio sudo[9402]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Jun 29 22:31:02 volumio volumio[5551]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Jun 29 22:31:02 volumio volumio[5551]: info: Output device has changed, restarting MPD Jun 29 22:31:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 29 22:31:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 22:31:02 volumio sudo[9408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 22:31:02 volumio sudo[9408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio volumio[5551]: info: Output device has changed, restarting Shairport Sync Jun 29 22:31:02 volumio sudo[9408]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 22:31:02 volumio sudo[9411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 22:31:02 volumio sudo[9411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio systemd[1]: Stopping Music Player Daemon... Jun 29 22:31:02 volumio volumio[5551]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 22:31:02 volumio volumio[5551]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio systemd[1]: mpd.service: Succeeded. Jun 29 22:31:02 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 22:31:02 volumio vtcs[7806]: [2025-06-29 22:31:02.373] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 29 22:31:02 volumio vtcs[7806]: [2025-06-29 22:31:02.374] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 29 22:31:02 volumio vtcs[7806]: [2025-06-29 22:31:02.374] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 29 22:31:02 volumio systemd[1]: Starting Music Player Daemon... Jun 29 22:31:02 volumio sudo[9420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 29 22:31:02 volumio volumio[5551]: info: Done. Jun 29 22:31:02 volumio sudo[9420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio sudo[9423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 29 22:31:02 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jun 29 22:31:02 volumio sudo[9423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 29 22:31:02 volumio systemd[1]: vtcs.service: Succeeded. Jun 29 22:31:02 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:02 volumio sudo[9420]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: MPD Permissions set Jun 29 22:31:02 volumio volumio[5551]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio sudo[9423]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 22:31:02 volumio volumio[5551]: info: FusionDsp - Bass-FusionDsp Jun 29 22:31:02 volumio sudo[9416]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 22:31:02 volumio sudo[9416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio sudo[9416]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:02 volumio volumio[5551]: info: Starting Shairport Sync Jun 29 22:31:02 volumio sudo[9448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 22:31:02 volumio sudo[9448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 29 22:31:02 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 29 22:31:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 29 22:31:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 29 22:31:02 volumio sudo[9448]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:02 volumio volumio[5551]: info: Shairport-Sync Started Jun 29 22:31:03 volumio mpd[9446]: Jun 29 22:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 29 22:31:03 volumio systemd[1]: Started Music Player Daemon. Jun 29 22:31:03 volumio sudo[9411]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:03 volumio volumio[5551]: error: updateQueue error: null Jun 29 22:31:05 volumio volumio[5551]: info: TidalConnect service stoped! Jun 29 22:31:05 volumio volumio[5551]: info: TidalConnect service stoped! Jun 29 22:31:05 volumio volumio[5551]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 29 22:31:05 volumio volumio[5551]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 29 22:31:05 volumio sudo[9467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 29 22:31:05 volumio sudo[9467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:31:05 volumio systemd[1]: Started Volumio Tidal Connect Service. Jun 29 22:31:05 volumio sudo[9467]: pam_unix(sudo:session): session closed for user root Jun 29 22:31:05 volumio volumio[5551]: info: Executing endpoint tc_getconfig Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 29 22:31:05 volumio vtcs[9469]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 29 22:31:05 volumio vtcs[9469]: STARTED TidalConnect services. Jun 29 22:31:05 volumio volumio[5551]: info: Executing endpoint tc_connect Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 29 22:31:05 volumio volumio[5551]: info: Connecting to TidalConnect Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:31:05 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:05 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:05 volumio volumio[5551]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:31:05 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:31:05 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:31:05 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:31:05 volumio volumio[5551]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 29 22:31:05 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:31:07 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:07 volumio volumio[5551]: info: FusionDsp - Bass-FusionDsp Jun 29 22:31:07 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:08 volumio volumio[5551]: info: TidalConnect service started! Jun 29 22:31:20 volumio volumio[5551]: info: CALLMETHOD: audio_interface fusiondsp reseteq Jun 29 22:31:20 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Jun 29 22:31:20 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:31:20 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:31:20 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:20 volumio volumio[5551]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:31:20 volumio volumio[5551]: info: FusionDsp - Bass-FusionDsp Jun 29 22:31:20 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:22 volumio volumio[5551]: info: CALLMETHOD: audio_interface fusiondsp reseteq Jun 29 22:31:22 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Jun 29 22:31:22 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:31:22 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:31:22 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:22 volumio volumio[5551]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:31:22 volumio volumio[5551]: info: FusionDsp - Bass-FusionDsp Jun 29 22:31:22 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:24 volumio volumio[5551]: info: CALLMETHOD: audio_interface fusiondsp reseteq Jun 29 22:31:24 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Jun 29 22:31:25 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:31:25 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:31:25 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:25 volumio volumio[5551]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:31:25 volumio volumio[5551]: info: FusionDsp - Bass-FusionDsp Jun 29 22:31:25 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:26 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:31:26 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:31:26 volumio volumio[5551]: info: Discovery: Getting this device information Jun 29 22:31:26 volumio volumio[5551]: info: CoreCommandRouter::volumioGetState Jun 29 22:31:26 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:31:32 volumio volumio[5551]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 29 22:31:32 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 29 22:31:32 volumio volumio[5551]: info: FusionDsp - value distance L------- 0 R 0 Jun 29 22:31:32 volumio volumio[5551]: info: FusionDsp - no delay needed Jun 29 22:31:32 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:31:32 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:31:33 volumio volumio[5551]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:31:33 volumio volumio[5551]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:31:33 volumio volumio[5551]: info: FusionDsp - no preset used Jun 29 22:31:33 volumio volumio[5551]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 29 22:31:50 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 29 22:31:52 volumio volumio[5551]: info: browseTIDALUri took 2531 milliseconds Jun 29 22:31:52 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/427520487 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/68883384 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/188267445 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/196495959 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891471 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/432660520 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/439551719 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260707 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260702 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/218147609 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/109112567 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/441994268 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70973227 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848682 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891472 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891469 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/282396342 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/104823314 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/202132356 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/81825679 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/244793638 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891473 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260700 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848696 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/209414276 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/82255830 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891478 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848683 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260699 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/291836911 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/166730009 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/202132360 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/109883276 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260704 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/216367062 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/224261411 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848685 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/215316277 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/191982060 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/272468079 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260703 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/29600613 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/70891481 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/293344016 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/219082722 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848689 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/112848688 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260708 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/430265765 Jun 29 22:31:52 volumio volumio[5551]: info: Preloading song: tidal://song/31260697 Jun 29 22:31:52 volumio volumio[5551]: info: Exploding uri tidal://song/427520487 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/68883384 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/188267445 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/196495959 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/70891471 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 222 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/432660520 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 210 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/439551719 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 197 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 199 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/31260707 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/31260702 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 222 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/218147609 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/109112567 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 253 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 250 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/441994268 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/70973227 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 257 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/112848682 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 226 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/70891472 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/70891469 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 212 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/282396342 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 421 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 221 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/104823314 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 211 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/202132356 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/81825679 in service tidal Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 210 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: explodeTIDALUri took 194 milliseconds Jun 29 22:31:53 volumio volumio[5551]: info: Exploding uri tidal://song/244793638 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/70891473 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 217 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/31260700 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 214 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/112848696 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 206 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/209414276 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 222 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/82255830 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 229 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/70891478 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 200 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/112848683 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/31260699 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 221 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/291836911 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 218 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 393 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/166730009 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 202 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/202132360 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 225 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/109883276 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/31260704 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 217 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/216367062 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 232 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/224261411 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/112848685 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 220 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 200 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/215316277 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/191982060 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 251 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/272468079 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 217 milliseconds Jun 29 22:31:54 volumio volumio[5551]: info: Exploding uri tidal://song/31260703 in service tidal Jun 29 22:31:54 volumio volumio[5551]: info: explodeTIDALUri took 210 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/29600613 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 195 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/70891481 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/293344016 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 210 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/219082722 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 225 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/112848689 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 213 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/112848688 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 214 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/31260708 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 205 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/430265765 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 220 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: Exploding uri tidal://song/31260697 in service tidal Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 221 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 193 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 198 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 203 milliseconds Jun 29 22:31:55 volumio volumio[5551]: info: explodeTIDALUri took 206 milliseconds Jun 29 22:31:56 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:31:56 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:31:56 volumio volumio[5551]: info: Discovery: Getting this device information Jun 29 22:31:56 volumio volumio[5551]: info: CoreCommandRouter::volumioGetState Jun 29 22:31:56 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 29 22:31:57 volumio volumio[5551]: info: browseTIDALUri took 357 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973226 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973228 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973229 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973230 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973231 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973232 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973233 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973234 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973235 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973236 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973237 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973238 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973239 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973240 Jun 29 22:31:57 volumio volumio[5551]: info: Preloading song: tidal://song/70973241 Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973226 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973228 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973229 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973230 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: explodeTIDALUri took 199 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973231 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973232 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973233 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: explodeTIDALUri took 258 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: explodeTIDALUri took 224 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973234 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: explodeTIDALUri took 220 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973235 in service tidal Jun 29 22:31:57 volumio volumio[5551]: info: explodeTIDALUri took 243 milliseconds Jun 29 22:31:57 volumio volumio[5551]: info: Exploding uri tidal://song/70973236 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 216 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: Exploding uri tidal://song/70973237 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 221 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: Exploding uri tidal://song/70973238 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 211 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: Exploding uri tidal://song/70973239 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 220 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: Exploding uri tidal://song/70973240 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 226 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: Exploding uri tidal://song/70973241 in service tidal Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 222 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 207 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 204 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 217 milliseconds Jun 29 22:31:58 volumio volumio[5551]: info: explodeTIDALUri took 202 milliseconds Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:31:59 volumio volumio[5551]: error: Failed request for metavolumio API Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:31:59 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:31:59 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:00 volumio volumio[5551]: error: Failed request for metavolumio API Jun 29 22:32:00 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:00 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:01 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:01 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:02 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:02 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:02 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:03 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:03 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:06 volumio volumio[5551]: error: Failed request for metavolumio API Jun 29 22:32:13 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 29 22:32:14 volumio volumio[5551]: info: browseTIDALUri took 459 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082765 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082766 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082767 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082768 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082769 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082771 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082772 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082777 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082784 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082786 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082787 Jun 29 22:32:14 volumio volumio[5551]: info: Preloading song: tidal://song/219082788 Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082765 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082766 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082767 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082768 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082769 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 232 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082771 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 225 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082772 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082777 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 269 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 236 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082784 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082786 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 263 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082787 in service tidal Jun 29 22:32:14 volumio volumio[5551]: info: explodeTIDALUri took 272 milliseconds Jun 29 22:32:14 volumio volumio[5551]: info: Exploding uri tidal://song/219082788 in service tidal Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 282 milliseconds Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 246 milliseconds Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 208 milliseconds Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 211 milliseconds Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 246 milliseconds Jun 29 22:32:15 volumio volumio[5551]: info: explodeTIDALUri took 208 milliseconds Jun 29 22:32:15 volumio volumio[5551]: error: Failed request for metavolumio API Jun 29 22:32:16 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:16 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:18 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::ClearQueue Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::stop Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::clearPlayQueue Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::saveQueue Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPushQueue Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::addQueueItems Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::addQueueItems Jun 29 22:32:18 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082765 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082765 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082766 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082766 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082767 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082767 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082768 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082768 Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPushQueue Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::saveQueue Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::updateTrackBlock Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrackBlock Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPlay Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::play index 3 Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::addQueueItems Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::addQueueItems Jun 29 22:32:18 volumio volumio[5551]: info: Preload queue cleared Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082769 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082769 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082771 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082771 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082772 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082772 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082777 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082777 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082784 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082784 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082786 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082786 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082787 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082787 Jun 29 22:32:18 volumio volumio[5551]: info: Adding Item to queue: tidal://song/219082788 Jun 29 22:32:18 volumio volumio[5551]: info: Using cached record of: tidal://song/219082788 Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::stop Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPushQueue Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::saveQueue Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::play index undefined Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::updateTrackBlock Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrackBlock Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::startPlaybackTimer Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:32:18 volumio volumio[5551]: info: [1751229138066] ControllerTidal::clearAddPlayTrack Jun 29 22:32:18 volumio volumio[5551]: info: Getting stream with soundQuality HI_RES Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand stop took 9 milliseconds Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 22:32:18 volumio volumio[5551]: info: Jun 29 22:32:18 volumio volumio[5551]: ---------------------------- MPD announces system playlist update Jun 29 22:32:18 volumio volumio[5551]: info: Ignoring MPD Status Update Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand clear took 2 milliseconds Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/219082768&soundQuality=HI_RES" Jun 29 22:32:18 volumio volumio[5551]: error: updateQueue error: null Jun 29 22:32:18 volumio volumio[5551]: info: Jun 29 22:32:18 volumio volumio[5551]: ---------------------------- MPD announces system playlist update Jun 29 22:32:18 volumio volumio[5551]: info: Ignoring MPD Status Update Jun 29 22:32:18 volumio volumio[5551]: info: ------------------------------ 6ms Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/219082768&soundQuality=HI_RES" took 2 milliseconds Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand play Jun 29 22:32:18 volumio volumio[5551]: info: ------------------------------ 3ms Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand play took 1 milliseconds Jun 29 22:32:18 volumio volumio[5551]: STREAMING PROXY: Handling url /?data=tidal://song/219082768&soundQuality=HI_RES Jun 29 22:32:18 volumio volumio[5551]: info: Executing endpoint getStreamUrltidal Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 29 22:32:18 volumio volumio[5551]: info: getStreamUrl took 256 milliseconds Jun 29 22:32:18 volumio volumio[5551]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidmZjAxYTI5M2FmMTA2MjliYjNmMDZiYmQzOWZkZjZmMl82Mi5tcDQ/0.flac?token=1751232738~ZTcwM2Q1ZjgwMjM1YTM4NTFiNjEzZDY2MTIxYTgxMTEzOTU3OGI4Yw== Jun 29 22:32:18 volumio volumio[5551]: STREAMING PROXY: Response: 200, length: 47416814 Jun 29 22:32:18 volumio volumio[5551]: info: Jun 29 22:32:18 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:32:18 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate, raw: Jun 29 22:32:18 volumio volumio[5551]: error: FusionDsp - invalid sample rate Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:32:18 volumio volumio[5551]: info: Jun 29 22:32:18 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand status took 41 milliseconds Jun 29 22:32:18 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand status took 16 milliseconds Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:32:18 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:32:18 volumio volumio[5551]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"219082768&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/219082768&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:32:18 volumio volumio[5551]: verbose: CURRENT POSITION 3 Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::syncState stateService play Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus stop Jun 29 22:32:18 volumio volumio[5551]: info: ------------------------------ 73ms Jun 29 22:32:18 volumio volumio[5551]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 29 22:32:18 volumio volumio[5551]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:32:18 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:32:18 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:32:18 volumio volumio[5551]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1737,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1223 Kbps","isStreaming":false,"title":"219082768&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/219082768&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:32:18 volumio volumio[5551]: verbose: CURRENT POSITION 3 Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::syncState stateService play Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus play Jun 29 22:32:18 volumio volumio[5551]: info: Received an update from plugin. extracting info from payload Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:18 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:18 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:18 volumio volumio[5551]: info: ------------------------------ 75ms Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:32:18 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:32:18 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:32:18 volumio volumio[5551]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:32:19 volumio volumio[5551]: STREAMING PROXY: Client dropped request, destroying Jun 29 22:32:21 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:21 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:21 volumio volumio[5551]: info: Executing endpoint getSimilarAlbums Jun 29 22:32:21 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 29 22:32:21 volumio volumio[5551]: info: Executing endpoint metavolumio Jun 29 22:32:21 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 29 22:32:23 volumio volumio[5551]: info: Jun 29 22:32:23 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:32:23 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand status took 1 milliseconds Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:32:23 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 3 Jun 29 22:32:23 volumio volumio[5551]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 29 22:32:23 volumio volumio[5551]: verbose: CURRENT POSITION 3 Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::syncState stateService stop Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus play Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::play index undefined Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 4 Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 4 Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::startPlaybackTimer Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 4 Jun 29 22:32:23 volumio volumio[5551]: info: [1751229143519] ControllerTidal::clearAddPlayTrack Jun 29 22:32:23 volumio volumio[5551]: info: Getting stream with soundQuality HI_RES Jun 29 22:32:23 volumio volumio[5551]: info: ------------------------------ 19ms Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 4 Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand stop took 9 milliseconds Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: play PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:32:23 volumio volumio[5551]: info: Jun 29 22:32:23 volumio volumio[5551]: ---------------------------- MPD announces system playlist update Jun 29 22:32:23 volumio volumio[5551]: info: Ignoring MPD Status Update Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand clear took 40 milliseconds Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/219082769&soundQuality=HI_RES" Jun 29 22:32:23 volumio volumio[5551]: error: updateQueue error: null Jun 29 22:32:23 volumio volumio[5551]: info: Jun 29 22:32:23 volumio volumio[5551]: ---------------------------- MPD announces system playlist update Jun 29 22:32:23 volumio volumio[5551]: info: Ignoring MPD Status Update Jun 29 22:32:23 volumio volumio[5551]: info: ------------------------------ 77ms Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/219082769&soundQuality=HI_RES" took 77 milliseconds Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand play Jun 29 22:32:23 volumio volumio[5551]: STREAMING PROXY: Handling url /?data=tidal://song/219082769&soundQuality=HI_RES Jun 29 22:32:23 volumio volumio[5551]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 29 22:32:23 volumio volumio[5551]: info: ------------------------------ 6ms Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand play took 5 milliseconds Jun 29 22:32:23 volumio volumio[5551]: info: Executing endpoint getStreamUrltidal Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 29 22:32:23 volumio volumio[5551]: info: getStreamUrl took 226 milliseconds Jun 29 22:32:23 volumio volumio[5551]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":4005,"userMessage":"Asset is not ready for playback"} Jun 29 22:32:23 volumio volumio[5551]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":4005,\"userMessage\":\"Asset is not ready for playback\"}"} Jun 29 22:32:23 volumio volumio[5551]: info: Jun 29 22:32:23 volumio volumio[5551]: ---------------------------- MPD announces state update: player Jun 29 22:32:23 volumio volumio[5551]: info: ControllerMpd::getState Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:32:23 volumio volumio[5551]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand status took 12 milliseconds Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::parseState Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand clearerror took 2 milliseconds Jun 29 22:32:23 volumio volumio[5551]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 29 22:32:23 volumio volumio[5551]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:32:23 volumio volumio[5551]: info: ControllerMpd::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::servicePushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: CorePlayQueue::getTrack 4 Jun 29 22:32:23 volumio volumio[5551]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"219082769&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/219082769&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:32:23 volumio volumio[5551]: verbose: CURRENT POSITION 4 Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::syncState stateService stop Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::syncState currentStatus stop Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: No code Jun 29 22:32:23 volumio volumio[5551]: info: CoreStateMachine::pushState Jun 29 22:32:23 volumio volumio[5551]: info: CoreCommandRouter::volumioPushState Jun 29 22:32:23 volumio volumio[5551]: info: ------------------------------ 39ms Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] CurState: stop PrevState: play Jun 29 22:32:23 volumio volumio[5551]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:32:24 volumio volumio[5551]: info: [ASDebug] Togle GPIO: OFF Jun 29 22:32:24 volumio volumio[5551]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:32:24 volumio volumio[5551]: Error: EBADF: bad file descriptor, write Jun 29 22:32:24 volumio volumio[5551]: at Object.writeSync (fs.js:711:3) Jun 29 22:32:24 volumio volumio[5551]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 29 22:32:24 volumio volumio[5551]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jun 29 22:32:24 volumio volumio[5551]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 29 22:32:24 volumio volumio[5551]: at listOnTimeout (internal/timers.js:554:17) Jun 29 22:32:24 volumio volumio[5551]: at processTimers (internal/timers.js:497:7) { Jun 29 22:32:24 volumio volumio[5551]: errno: -9, Jun 29 22:32:24 volumio volumio[5551]: syscall: 'write', Jun 29 22:32:24 volumio volumio[5551]: code: 'EBADF' Jun 29 22:32:24 volumio volumio[5551]: } Jun 29 22:32:24 volumio volumio[5551]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:32:25 volumio sudo[9692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-29 22:31 Jun 29 22:32:25 volumio sudo[9692]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Jun 2025 03:18:50 PM CEST" VOLUMIO_VERSION="3.818" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d5105a50d571ff4623988fe02d73334b"