-- Logs begin at Fri 2025-02-21 15:54:34 CET, end at Fri 2025-02-21 15:57:04 CET. -- Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Feb 21 15:56:08 volumiorp5 sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 15:56:08 volumiorp5 sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 15:56:08 volumiorp5 sudo[2140]: pam_unix(sudo:session): session closed for user root Feb 21 15:56:08 volumiorp5 sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 15:56:08 volumiorp5 sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 15:56:08 volumiorp5 volumio[1311]: info: MPD Permissions set Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 systemd[1]: Stopping Music Player Daemon... Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 15:56:08 volumiorp5 volumio[1311]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 15:56:08 volumiorp5 systemd[1]: mpd.service: Succeeded. Feb 21 15:56:08 volumiorp5 systemd[1]: Stopped Music Player Daemon. Feb 21 15:56:08 volumiorp5 volumio[1311]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 15:56:08 volumiorp5 systemd[1]: Starting Music Player Daemon... Feb 21 15:56:08 volumiorp5 volumio[1311]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 15:56:08 volumiorp5 sudo[2149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 15:56:08 volumiorp5 sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 15:56:08 volumiorp5 sudo[2149]: pam_unix(sudo:session): session closed for user root Feb 21 15:56:08 volumiorp5 mpd[2151]: Feb 21 15:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 15:56:08 volumiorp5 systemd[1]: Started Music Player Daemon. Feb 21 15:56:08 volumiorp5 sudo[2143]: pam_unix(sudo:session): session closed for user root Feb 21 15:56:08 volumiorp5 volumio[1311]: error: updateQueue error: null Feb 21 15:56:15 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 21 15:56:15 volumiorp5 volumio[1311]: info: browseTIDALUri took 355 milliseconds Feb 21 15:56:15 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:15 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:16 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 21 15:56:18 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 21 15:56:19 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 21 15:56:20 volumiorp5 volumio[1311]: info: browseTIDALUri took 753 milliseconds Feb 21 15:56:20 volumiorp5 volumio[1311]: info: browseTIDALUri took 1923 milliseconds Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752285 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699712 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752279 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752284 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699727 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699736 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397033 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397081 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752274 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397056 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397038 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752269 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397059 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699719 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397034 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752272 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699718 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/65436584 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397032 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/1275533 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/280726890 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699713 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/280737761 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699728 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699724 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/123965688 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/4921568 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699722 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752280 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699733 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699721 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/2528036 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699720 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699725 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699735 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397043 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397037 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699729 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699715 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397028 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397031 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752268 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699714 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/4872243 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699734 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699738 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/70296470 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397039 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699730 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752286 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752285 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699712 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752279 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752284 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699727 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699736 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397033 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397081 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752274 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397056 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397038 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752269 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397059 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699719 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397034 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752272 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699718 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/65436584 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397032 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/1275533 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/280726890 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699713 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/280737761 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699728 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699724 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/123965688 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/4921568 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699722 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752280 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699733 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699721 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/2528036 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699720 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699725 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699735 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397043 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397037 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699729 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699715 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397028 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397031 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752268 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699714 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/4872243 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699734 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699738 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/70296470 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/7397039 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/199699730 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Preloading song: tidal://song/35752286 Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752285 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699712 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752279 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752284 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699727 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699736 in service tidal Feb 21 15:56:20 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397033 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 342 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397081 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 350 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752274 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397056 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 356 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397038 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 360 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 336 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752269 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 339 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397059 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699719 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 379 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 346 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397034 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752272 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 360 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 344 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699718 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 342 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/65436584 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 348 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397032 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 350 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/1275533 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: explodeTIDALUri took 339 milliseconds Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/280726890 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699713 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/280737761 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699728 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699724 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/123965688 in service tidal Feb 21 15:56:21 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/4921568 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699722 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752280 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699733 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699721 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/2528036 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699720 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699725 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699735 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397043 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397037 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699729 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699715 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397028 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397031 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752268 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699714 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/4872243 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699734 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699738 in service tidal Feb 21 15:56:22 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/70296470 in service tidal Feb 21 15:56:23 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397039 in service tidal Feb 21 15:56:23 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699730 in service tidal Feb 21 15:56:23 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752286 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::ClearQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::stop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::stPlaybackTimer Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:24 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:24 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::serviceStop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::serviceStop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: [1740149784611] ControllerTidal::stop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:24 volumiorp5 volumio[1311]: info: ControllerMpd::stop Feb 21 15:56:24 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::clearPlayQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::saveQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::addQueueItems Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::addQueueItems Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752285 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752285 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699712 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/199699712 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752279 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752279 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::saveQueue Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPlay Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::play index 2 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::addQueueItems Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::addQueueItems Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752284 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752284 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699727 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/199699727 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699736 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/199699736 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397033 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/7397033 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397081 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/7397081 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752274 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752274 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397056 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/7397056 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397038 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/7397038 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752269 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752269 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397059 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/7397059 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699719 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/199699719 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397034 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397034 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752272 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752272 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699718 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699718 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/65436584 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/65436584 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397032 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397032 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/1275533 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/1275533 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/280726890 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/280726890 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699713 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699713 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/280737761 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/280737761 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699728 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699728 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699724 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699724 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/123965688 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/123965688 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/4921568 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/4921568 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699722 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699722 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752280 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752280 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699733 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699733 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699721 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699721 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/2528036 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/2528036 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699720 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699720 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699725 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699725 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699735 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699735 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397043 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397043 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397037 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397037 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699729 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699729 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699715 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699715 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397028 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397028 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397031 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397031 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752268 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752268 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699714 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699714 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/4872243 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/4872243 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699734 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699734 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699738 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699738 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/70296470 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/70296470 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/7397039 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/7397039 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/199699730 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/199699730 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752286 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Exploding uri tidal://song/35752286 in service tidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::stop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::play index undefined Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 2 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::startPlaybackTimer Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 2 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 15:56:24 volumiorp5 volumio[1311]: info: [1740149784643] ControllerTidal::clearAddPlayTrack Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Getting stream with soundQuality HIGH Feb 21 15:56:24 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:24 volumiorp5 volumio[1311]: info: [ASDebug] CurState: stop PrevState: play Feb 21 15:56:24 volumiorp5 volumio[1311]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 21 15:56:24 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:24 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 70 milliseconds Feb 21 15:56:24 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 38 milliseconds Feb 21 15:56:24 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Feb 21 15:56:24 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:24 volumiorp5 volumio[1311]: info: sendMpdCommand clear took 1 milliseconds Feb 21 15:56:24 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH" Feb 21 15:56:24 volumiorp5 volumio[1311]: error: updateQueue error: null Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Feb 21 15:56:24 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:24 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:24 volumiorp5 volumio[1311]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH" took 0 milliseconds Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:24 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand play Feb 21 15:56:24 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:24 volumiorp5 volumio[1311]: info: sendMpdCommand play took 1 milliseconds Feb 21 15:56:24 volumiorp5 volumio[1311]: STREAMING PROXY: Handling url /?data=tidal://song/35752279&soundQuality=HIGH Feb 21 15:56:24 volumiorp5 volumio[1311]: info: Executing endpoint getStreamUrltidal Feb 21 15:56:24 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 21 15:56:25 volumiorp5 volumio[1311]: info: [ASDebug] Togle GPIO: OFF Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5397 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5249 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5109 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 4910 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5331 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5030 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5185 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 4838 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 4790 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 4905 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5112 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5020 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5281 milliseconds Feb 21 15:56:26 volumiorp5 volumio[1311]: info: explodeTIDALUri took 5434 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::ClearQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::stop Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::clearPlayQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::saveQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::addQueueItems Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::addQueueItems Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Preload queue cleared Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Adding Item to queue: tidal://song/35752279 Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Using cached record of: tidal://song/35752279 Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::saveQueue Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPlay Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::play index 0 Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::stop Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::play index undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::startPlaybackTimer Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 15:56:30 volumiorp5 volumio[1311]: info: [1740149790841] ControllerTidal::clearAddPlayTrack Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Getting stream with soundQuality HIGH Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Feb 21 15:56:30 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 2 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Feb 21 15:56:30 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand status took 1 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand clear took 1 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH" Feb 21 15:56:30 volumiorp5 volumio[1311]: error: updateQueue error: null Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Feb 21 15:56:30 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:30 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH" took 1 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:30 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand play Feb 21 15:56:30 volumiorp5 volumio[1311]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 21 15:56:30 volumiorp5 volumio[1311]: info: ------------------------------ 3ms Feb 21 15:56:30 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:30 volumiorp5 volumio[1311]: info: sendMpdCommand play took 1 milliseconds Feb 21 15:56:30 volumiorp5 volumio[1311]: STREAMING PROXY: Handling url /?data=tidal://song/35752279&soundQuality=HIGH Feb 21 15:56:30 volumiorp5 volumio[1311]: info: Executing endpoint getStreamUrltidal Feb 21 15:56:30 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9739 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9603 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9556 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9471 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9522 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9681 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9446 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9147 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9249 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9404 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9070 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9129 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9237 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9338 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9389 milliseconds Feb 21 15:56:31 volumiorp5 volumio[1311]: info: explodeTIDALUri took 9834 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12124 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12158 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12173 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12187 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12190 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12210 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12213 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 12227 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 13994 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 13749 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 13923 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 13891 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 13858 milliseconds Feb 21 15:56:36 volumiorp5 volumio[1311]: info: explodeTIDALUri took 14036 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17167 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17170 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17184 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17190 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17210 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17212 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17214 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17218 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17226 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17232 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17232 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17242 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17258 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17257 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17266 milliseconds Feb 21 15:56:41 volumiorp5 volumio[1311]: info: explodeTIDALUri took 17332 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22259 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22263 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22286 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22283 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22302 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22301 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22315 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22319 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22342 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22350 milliseconds Feb 21 15:56:46 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22352 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: info: explodeTIDALUri took 22372 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushQueue Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CorePlayQueue::saveQueue Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:47 volumiorp5 volumio[1311]: info: getStreamUrl took 22396 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: STREAMING PROXY: Got real url: http://amz-pr-cf.audio.tidal.com/0b6166ff3b4555b6b30d1d4a26c563d6_37.mp4?Expires=1740153406&Signature=pzflX9sPbWBvajUcNtza-wf3lhMunXWH-qSdrgUYZJTK1rlD3fmhQ7NGiQQEYuuYZ2v7980j8qSBool2~S8Cq-Fnl3071GbYe~8n3TqUz0ERo07DNauWrWYZlRUVsL1yvp1KC9i7LrBLhPQOCGcyhmsMJFxf~lO1Xlrly5Irq4A3yVW~6JxC36LzeWzE3kRHA6e6euRbWVSSyLDR071MvmWRDNcg5XrK7O8O1HQz3MOUUjnnLI~toOV5365VhXO-e-da9pYeNxDhZGoay-x8OUp5WmTml6jSOCCa81YTXdjpTmhiLEmNy-JgRj3eTkWbLLrxLIoQbmQdil142ZeWqA__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 21 15:56:47 volumiorp5 volumio[1311]: STREAMING PROXY: Response: 200, length: 12561981 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: getStreamUrl took 16346 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: STREAMING PROXY: Got real url: http://amz-pr-cf.audio.tidal.com/0b6166ff3b4555b6b30d1d4a26c563d6_37.mp4?Expires=1740153406&Signature=pzflX9sPbWBvajUcNtza-wf3lhMunXWH-qSdrgUYZJTK1rlD3fmhQ7NGiQQEYuuYZ2v7980j8qSBool2~S8Cq-Fnl3071GbYe~8n3TqUz0ERo07DNauWrWYZlRUVsL1yvp1KC9i7LrBLhPQOCGcyhmsMJFxf~lO1Xlrly5Irq4A3yVW~6JxC36LzeWzE3kRHA6e6euRbWVSSyLDR071MvmWRDNcg5XrK7O8O1HQz3MOUUjnnLI~toOV5365VhXO-e-da9pYeNxDhZGoay-x8OUp5WmTml6jSOCCa81YTXdjpTmhiLEmNy-JgRj3eTkWbLLrxLIoQbmQdil142ZeWqA__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 21 15:56:47 volumiorp5 volumio[1311]: STREAMING PROXY: Response: 200, length: 12561981 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: Feb 21 15:56:47 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate from file: 192000 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: camilladsp stopping service pid 2013... Feb 21 15:56:47 volumiorp5 volumio[1311]: info: camilladsp service terminated, instance 1 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 21 15:56:47 volumiorp5 volumio[1311]: info: camilladsp service started and running in background, instance 1 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate from file: 192000 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 21 15:56:47 volumiorp5 volumio[1311]: info: Feb 21 15:56:47 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:47 volumiorp5 volumio[1311]: info: sendMpdCommand status took 24 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:47 volumiorp5 volumio[1311]: info: sendMpdCommand status took 1 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35752279&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH","trackType":"tidal"} Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: CURRENT POSITION 0 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState stateService play Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ------------------------------ 29ms Feb 21 15:56:47 volumiorp5 volumio[1311]: error: FusionDsp - WebSocket error: [object Object] Feb 21 15:56:47 volumiorp5 volumio[1311]: info: camilladsp spawned new process with pid 2264, instance 1, run: true Feb 21 15:56:47 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: STATE SERVICE {"status":"play","position":0,"seek":937,"duration":313,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35752279&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/35752279&soundQuality=HIGH","trackType":"tidal"} Feb 21 15:56:47 volumiorp5 volumio[1311]: verbose: CURRENT POSITION 0 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState stateService play Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState currentStatus play Feb 21 15:56:47 volumiorp5 volumio[1311]: info: Received an update from plugin. extracting info from payload Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:47 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:47 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:47 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:47 volumiorp5 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 21 15:56:47 volumiorp5 volumio[1311]: info: ------------------------------ 29ms Feb 21 15:56:47 volumiorp5 volumio[1311]: info: [ASDebug] CurState: play PrevState: stop Feb 21 15:56:47 volumiorp5 volumio[1311]: info: [ASDebug] Togle GPIO: ON Feb 21 15:56:47 volumiorp5 volumio[1311]: info: [ASDebug] CurState: play PrevState: play Feb 21 15:56:47 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:47 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:47 volumiorp5 volumio[1311]: STREAMING PROXY: Client dropped request, destroying Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioNext Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::next Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [1740149812900] ControllerTidal::next Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::next Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::stop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::stPlaybackTimer Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:52 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::serviceStop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 0 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::serviceStop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [1740149812908] ControllerTidal::stop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ControllerMpd::stop Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [ASDebug] CurState: stop PrevState: play Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 21 15:56:52 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Feb 21 15:56:52 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 10 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::play index undefined Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::startPlaybackTimer Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [1740149812919] ControllerTidal::clearAddPlayTrack Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Getting stream with soundQuality HIGH Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::updateTrackBlock Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrackBlock Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand status took 1 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 0 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Feb 21 15:56:52 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand clear took 1 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH" Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:52 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:52 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ------------------------------ 12ms Feb 21 15:56:52 volumiorp5 volumio[1311]: error: updateQueue error: null Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Feb 21 15:56:52 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ------------------------------ 9ms Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH" took 9 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:52 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand play Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [ASDebug] CurState: stop PrevState: play Feb 21 15:56:52 volumiorp5 volumio[1311]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 21 15:56:52 volumiorp5 volumio[1311]: STREAMING PROXY: Handling url /?data=tidal://song/35752284&soundQuality=HIGH Feb 21 15:56:52 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:52 volumiorp5 volumio[1311]: info: ------------------------------ 5ms Feb 21 15:56:52 volumiorp5 volumio[1311]: info: sendMpdCommand play took 5 milliseconds Feb 21 15:56:52 volumiorp5 volumio[1311]: info: Executing endpoint getStreamUrltidal Feb 21 15:56:52 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 21 15:56:52 volumiorp5 volumio[1311]: info: camilladsp spawned new process with pid 2288, instance 1, run: true Feb 21 15:56:52 volumiorp5 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 21 15:56:53 volumiorp5 volumio[1311]: info: [ASDebug] Togle GPIO: OFF Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPlay Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::play index undefined Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::startPlaybackTimer Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [1740149814079] ControllerTidal::clearAddPlayTrack Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Getting stream with soundQuality HIGH Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand stop took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand status took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand clear took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH" Feb 21 15:56:54 volumiorp5 volumio[1311]: error: updateQueue error: null Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces system playlist update Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Ignoring MPD Status Update Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH" took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand play Feb 21 15:56:54 volumiorp5 volumio[1311]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 2ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 1ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand play took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: STREAMING PROXY: Handling url /?data=tidal://song/35752284&soundQuality=HIGH Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Executing endpoint getStreamUrltidal Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 21 15:56:54 volumiorp5 volumio[1311]: info: getStreamUrl took 363 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: STREAMING PROXY: Got real url: http://amz-pr-cf.audio.tidal.com/eff917fe93be0649bc4d3bfae8f32477_37.mp4?Expires=1740153414&Signature=hjZP0fNRcbcVWx1crIVDVpmtjZld3I3EQ8xle5MvfpwLCAqN47gYUVAnGAn9WIl1mtenx89~y6Kk4lhFnkmCfcRgvzICdUx7Za2nV7LmIeUIPZAy~GrNyo3WT~x7GnGaCxqgt5MMblhp4rZCKzx~HJ~aXtgqL31olT7Ya1UpUMgtQve8-UQLZhbshBX78beYdXL1W-2yWLJOej0H9nn9Cvd64azbu9y4R4jWCwKns~y0vb9pLtKbzm0Z84eb~HPDgwX44s6-h2b7rkh41u9Qy2Yagkmmtelz1H372PcAIBuu9FS9Mz2kpsq~jSlQRj2x7ACYoJ8XO26FC5VXyb0FQw__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 21 15:56:54 volumiorp5 volumio[1311]: STREAMING PROXY: Response: 200, length: 10802695 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate from file: 192000 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - ---- read samplerate from file: 192000 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand status took 10 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand status took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35752284&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH","trackType":"tidal"} Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: CURRENT POSITION 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState stateService play Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 11ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":269,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35752284&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH","trackType":"tidal"} Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: CURRENT POSITION 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState stateService play Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState currentStatus play Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Received an update from plugin. extracting info from payload Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 16ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [ASDebug] CurState: play PrevState: stop Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [ASDebug] Togle GPIO: ON Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [ASDebug] CurState: play PrevState: play Feb 21 15:56:54 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:54 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 21 15:56:54 volumiorp5 volumio[1311]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 21 15:56:54 volumiorp5 volumio[1311]: STREAMING PROXY: Client dropped request, destroying Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::pause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::stPlaybackTimer Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::servicePause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [1740149814954] ControllerTidal::pause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::pause Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand pause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: Feb 21 15:56:54 volumiorp5 volumio[1311]: ---------------------------- MPD announces state update: player Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand pause took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::getState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand status Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand status took 0 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseState Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: ControllerMpd::parseTrackInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ControllerMpd::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::servicePushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CorePlayQueue::getTrack 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1759,"duration":269,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35752284&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/35752284&soundQuality=HIGH","trackType":"tidal"} Feb 21 15:56:54 volumiorp5 volumio[1311]: verbose: CURRENT POSITION 1 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState stateService pause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::syncState currentStatus pause Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::pushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreCommandRouter::volumioPushState Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Feb 21 15:56:54 volumiorp5 volumio[1311]: info: MRS: Pushing multiroomSync output Feb 21 15:56:54 volumiorp5 volumio[1311]: info: CoreStateMachine::stPlaybackTimer Feb 21 15:56:54 volumiorp5 volumio[1311]: info: ------------------------------ 9ms Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [ASDebug] CurState: pause PrevState: play Feb 21 15:56:54 volumiorp5 volumio[1311]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 21 15:56:54 volumiorp5 volumio[1311]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 21 15:56:54 volumiorp5 volumio[1311]: info: camilladsp spawned new process with pid 2299, instance 1, run: true Feb 21 15:56:54 volumiorp5 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 21 15:56:55 volumiorp5 volumio[1311]: info: [ASDebug] Togle GPIO: OFF Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 21 15:57:00 volumiorp5 volumio[1311]: info: Received Get System Version Feb 21 15:57:00 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 15:57:03 volumiorp5 volumio[1311]: info: Command Delete User Data Received Feb 21 15:57:03 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: inputs , setMCUFactoryReset Feb 21 15:57:03 volumiorp5 volumio[1311]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setMCUFactoryReset] in plugin inputs Feb 21 15:57:04 volumiorp5 volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , deleteUserData Feb 21 15:57:04 volumiorp5 volumio[1311]: info: Created User Data delete file, rebooting Feb 21 15:57:04 volumiorp5 volumio[1311]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Feb 21 15:57:04 volumiorp5 volumio[1311]: info: PLUGIN onReboot : networkfs Feb 21 15:57:04 volumiorp5 volumio[1311]: info: PLUGIN onReboot : audiophonicsonoff Feb 21 15:57:04 volumiorp5 volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 15:57:04 volumiorp5 volumio[1311]: TypeError: Cannot read property 'writeSync' of undefined Feb 21 15:57:04 volumiorp5 volumio[1311]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Feb 21 15:57:04 volumiorp5 volumio[1311]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Feb 21 15:57:04 volumiorp5 volumio[1311]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Feb 21 15:57:04 volumiorp5 volumio[1311]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 21 15:57:04 volumiorp5 volumio[1311]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 21 15:57:04 volumiorp5 volumio[1311]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Feb 21 15:57:04 volumiorp5 volumio[1311]: at CoreCommandRouter.reboot (/volumio/app/index.js:1344:22) Feb 21 15:57:04 volumiorp5 volumio[1311]: at /volumio/app/plugins/system_controller/system/index.js:719:26 Feb 21 15:57:04 volumiorp5 volumio[1311]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Feb 21 15:57:04 volumiorp5 volumio[1311]: at /data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:43:10 Feb 21 15:57:04 volumiorp5 volumio[1311]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Feb 21 15:57:04 volumiorp5 volumio[1311]: at FSReqCallback.oncomplete (fs.js:171:23) Feb 21 15:57:04 volumiorp5 volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 15:57:04 volumiorp5 sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-21 15:56 Feb 21 15:57:04 volumiorp5 sudo[2346]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"