-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Thu 2025-06-19 15:49:18 CST. -- Jun 19 15:48:21 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:21+08:00" level=trace msg="received accesspoint ping" Jun 19 15:48:21 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:21+08:00" level=trace msg="received accesspoint pong ack" Jun 19 15:48:25 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:25+08:00" level=trace msg="sent dealer ping" Jun 19 15:48:25 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:25+08:00" level=trace msg="received dealer pong" Jun 19 15:48:36 ft1905-febf volumio[17366]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:48:36 ft1905-febf volumio[17366]: DATE: 'Thu, 19 Jun 2025 07:48:36 GMT', Jun 19 15:48:36 ft1905-febf volumio[17366]: EXT: '', Jun 19 15:48:36 ft1905-febf volumio[17366]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:48:36 ft1905-febf volumio[17366]: SERVER: 'eXtensible UPnP agent', Jun 19 15:48:36 ft1905-febf volumio[17366]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:48:36 ft1905-febf volumio[17366]: USN: Jun 19 15:48:36 ft1905-febf volumio[17366]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:48:36 ft1905-febf volumio[17366]: Device IP: 192.168.2.1 Jun 19 15:48:36 ft1905-febf volumio[17366]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:48:36 ft1905-febf volumio[17366]: DATE: 'Thu, 19 Jun 2025 07:48:36 GMT', Jun 19 15:48:36 ft1905-febf volumio[17366]: EXT: '', Jun 19 15:48:36 ft1905-febf volumio[17366]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:48:36 ft1905-febf volumio[17366]: SERVER: 'eXtensible UPnP agent', Jun 19 15:48:36 ft1905-febf volumio[17366]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:48:36 ft1905-febf volumio[17366]: USN: Jun 19 15:48:36 ft1905-febf volumio[17366]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:48:36 ft1905-febf volumio[17366]: Device IP: 192.168.2.1 Jun 19 15:48:40 ft1905-febf sshd[18229]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 19 15:48:40 ft1905-febf sshd[18229]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 19 15:48:40 ft1905-febf sshd[18229]: rexec line 8: Deprecated option ServerKeyBits Jun 19 15:48:40 ft1905-febf sshd[18229]: rexec line 14: Deprecated option RSAAuthentication Jun 19 15:48:40 ft1905-febf sshd[18229]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 19 15:48:40 ft1905-febf sshd[18229]: reprocess config line 14: Deprecated option RSAAuthentication Jun 19 15:48:40 ft1905-febf sshd[18229]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 19 15:48:40 ft1905-febf sshd[18229]: Accepted password for volumio from 192.168.2.154 port 53020 ssh2 Jun 19 15:48:40 ft1905-febf sshd[18229]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 19 15:48:40 ft1905-febf systemd-logind[2240]: New session c16 of user volumio. Jun 19 15:48:40 ft1905-febf systemd[1]: Started Session c16 of user volumio. Jun 19 15:48:42 ft1905-febf sshd[18244]: Received disconnect from 192.168.2.154 port 53020:11: Jun 19 15:48:42 ft1905-febf sshd[18244]: Disconnected from user volumio 192.168.2.154 port 53020 Jun 19 15:48:42 ft1905-febf sshd[18229]: pam_unix(sshd:session): session closed for user volumio Jun 19 15:48:43 ft1905-febf sshd[18255]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 19 15:48:43 ft1905-febf sshd[18255]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 19 15:48:43 ft1905-febf sshd[18255]: rexec line 8: Deprecated option ServerKeyBits Jun 19 15:48:43 ft1905-febf sshd[18255]: rexec line 14: Deprecated option RSAAuthentication Jun 19 15:48:43 ft1905-febf sshd[18255]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 19 15:48:43 ft1905-febf systemd[1]: session-c16.scope: Succeeded. Jun 19 15:48:43 ft1905-febf systemd-logind[2240]: Session c16 logged out. Waiting for processes to exit. Jun 19 15:48:43 ft1905-febf systemd-logind[2240]: Removed session c16. Jun 19 15:48:43 ft1905-febf sshd[18255]: reprocess config line 14: Deprecated option RSAAuthentication Jun 19 15:48:43 ft1905-febf sshd[18255]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 19 15:48:43 ft1905-febf sshd[18255]: Accepted password for volumio from 192.168.2.154 port 53054 ssh2 Jun 19 15:48:43 ft1905-febf sshd[18255]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 19 15:48:43 ft1905-febf systemd-logind[2240]: New session c17 of user volumio. Jun 19 15:48:43 ft1905-febf systemd[1]: Started Session c17 of user volumio. Jun 19 15:48:43 ft1905-febf sudo[18276]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop volumio.service Jun 19 15:48:43 ft1905-febf sudo[18276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:43 ft1905-febf systemd[1]: Stopping Volumio Backend Module... Jun 19 15:48:44 ft1905-febf socketio[2399]: [2025-06-19 15:48:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 19 15:48:44 ft1905-febf socketio[2399]: [2025-06-19 15:48:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 19 15:48:44 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:44+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 19 15:48:44 ft1905-febf systemd[1]: volumio.service: Succeeded. Jun 19 15:48:44 ft1905-febf systemd[1]: Stopped Volumio Backend Module. Jun 19 15:48:44 ft1905-febf sudo[18276]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:44 ft1905-febf sudo[18285]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start volumio.service Jun 19 15:48:44 ft1905-febf sudo[18285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:44 ft1905-febf systemd[1]: Started dynamicswap service. Jun 19 15:48:44 ft1905-febf systemd[1]: Started Volumio Backend Module. Jun 19 15:48:44 ft1905-febf sudo[18285]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:44 ft1905-febf systemd[1]: dynamicswap.service: Succeeded. Jun 19 15:48:44 ft1905-febf sshd[18273]: Received disconnect from 192.168.2.154 port 53054:11: Jun 19 15:48:44 ft1905-febf sshd[18273]: Disconnected from user volumio 192.168.2.154 port 53054 Jun 19 15:48:44 ft1905-febf sshd[18255]: pam_unix(sshd:session): session closed for user volumio Jun 19 15:48:44 ft1905-febf systemd[1]: session-c17.scope: Succeeded. Jun 19 15:48:44 ft1905-febf systemd-logind[2240]: Session c17 logged out. Waiting for processes to exit. Jun 19 15:48:44 ft1905-febf systemd-logind[2240]: Removed session c17. Jun 19 15:48:48 ft1905-febf volumio[18293]: info: ------------------------------------------- Jun 19 15:48:48 ft1905-febf volumio[18293]: info: ----- Volumio2 ---- Jun 19 15:48:48 ft1905-febf volumio[18293]: info: ------------------------------------------- Jun 19 15:48:48 ft1905-febf volumio[18293]: info: ----- System startup ---- Jun 19 15:48:48 ft1905-febf volumio[18293]: info: ------------------------------------------- Jun 19 15:48:49 ft1905-febf socketio[2399]: [2025-06-19 15:48:49] [connect] Successful connection Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Found new core plugin music_service/cdplayer. Adding it Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Plugin folders cleanup Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning into folder /volumio/app/plugins/ Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category audio_interface Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category miscellanea Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category music_service Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category plugins.json Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category system_controller Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning category user_interface Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Scanning into folder /data/plugins/ Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Plugin folders cleanup completed Jun 19 15:48:49 ft1905-febf volumio[18293]: info: ------------------------------------------- Jun 19 15:48:49 ft1905-febf volumio[18293]: info: ----- Core plugins startup ---- Jun 19 15:48:49 ft1905-febf volumio[18293]: info: ------------------------------------------- Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Loading plugins from folder /volumio/app/plugins/ Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Adding plugin upnp to MyMusic Plugins Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Loading plugins from folder /data/plugins/ Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Loading plugin "system"... Jun 19 15:48:49 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jun 19 15:48:49 ft1905-febf volumio[18293]: info: Loading plugin "appearance"... Jun 19 15:48:49 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Loading plugin "network"... Jun 19 15:48:50 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Refreshing Cached IP Addresses Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Loading plugin "services"... Jun 19 15:48:50 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jun 19 15:48:50 ft1905-febf sudo[18324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Loading plugin "alsa_controller"... Jun 19 15:48:50 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jun 19 15:48:50 ft1905-febf sudo[18324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:50 ft1905-febf sudo[18327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 15:48:50 ft1905-febf sudo[18324]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Applying required configuration parameters for plugin alsa_controller Jun 19 15:48:50 ft1905-febf sudo[18327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:50 ft1905-febf sudo[18327]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Loading plugin "wizard"... Jun 19 15:48:50 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jun 19 15:48:50 ft1905-febf volumio[18293]: info: Loading plugin "volumio_command_line_client"... Jun 19 15:48:50 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jun 19 15:48:50 ft1905-febf volumio[18293]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jun 19 15:48:50 ft1905-febf volumio[18293]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jun 19 15:48:50 ft1905-febf volumio[18293]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jun 19 15:48:50 ft1905-febf volumio[18293]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jun 19 15:48:50 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:51 ft1905-febf volumio[18293]: info: Loading plugin "upnp"... Jun 19 15:48:51 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jun 19 15:48:51 ft1905-febf volumio[18293]: info: [1750319331148] Starting Upmpd Daemon Jun 19 15:48:51 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 15:48:51 ft1905-febf volumio[18293]: info: Loading plugin "my_music"... Jun 19 15:48:51 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jun 19 15:48:51 ft1905-febf volumio[18293]: info: Loading plugin "mpd"... Jun 19 15:48:51 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jun 19 15:48:51 ft1905-febf volumio[18293]: info: Creating MPD Configuration file Jun 19 15:48:51 ft1905-febf volumio[18293]: info: Loading plugin "upnp_browser"... Jun 19 15:48:51 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jun 19 15:48:51 ft1905-febf sudo[18353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 15:48:51 ft1905-febf sudo[18353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:52 ft1905-febf sudo[18355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 15:48:52 ft1905-febf sudo[18353]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:52 ft1905-febf sudo[18355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:48:52 ft1905-febf systemd[1]: Stopping Music Player Daemon... Jun 19 15:48:52 ft1905-febf systemd[1]: mpd.service: Succeeded. Jun 19 15:48:52 ft1905-febf systemd[1]: Stopped Music Player Daemon. Jun 19 15:48:52 ft1905-febf systemd[1]: Starting Music Player Daemon... Jun 19 15:48:52 ft1905-febf volumio[18293]: info: Loading plugin "networkfs"... Jun 19 15:48:52 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jun 19 15:48:52 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 15:48:52 ft1905-febf volumio[18293]: info: Loading plugin "alarm-clock"... Jun 19 15:48:52 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jun 19 15:48:53 ft1905-febf volumio[18293]: info: Loading plugin "airplay_emulation"... Jun 19 15:48:53 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jun 19 15:48:53 ft1905-febf volumio[18293]: Shairport Sync D-Bus 服务已注册。 Jun 19 15:48:53 ft1905-febf volumio[18293]: info: Starting Shairport Sync Jun 19 15:48:53 ft1905-febf volumio[18293]: info: Loading plugin "last_100"... Jun 19 15:48:53 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jun 19 15:48:53 ft1905-febf volumio[18293]: info: Loading plugin "spop"... Jun 19 15:48:53 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jun 19 15:48:54 ft1905-febf volumio[18293]: SPOTIFY: resetSpotifyState Jun 19 15:48:54 ft1905-febf volumio[18293]: info: Loading plugin "webradio"... Jun 19 15:48:54 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jun 19 15:48:54 ft1905-febf mpd[18369]: Jun 19 15:48 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 15:48:54 ft1905-febf mpd[18369]: Jun 19 15:48 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 19 15:48:54 ft1905-febf mpd[18369]: Jun 19 15:48 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 19 15:48:54 ft1905-febf systemd[1]: Started Music Player Daemon. Jun 19 15:48:54 ft1905-febf sudo[18355]: pam_unix(sudo:session): session closed for user root Jun 19 15:48:55 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:55+08:00" level=trace msg="sent dealer ping" Jun 19 15:48:55 ft1905-febf go-librespot[17580]: time="2025-06-19T15:48:55+08:00" level=trace msg="received dealer pong" Jun 19 15:48:56 ft1905-febf volumio[18293]: info: Loading plugin "yandex"... Jun 19 15:48:56 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/yandex/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Yandex plugin started Jun 19 15:48:57 ft1905-febf volumio[18293]: error: ATTENTION!!!: Plugin yandex does not return adequate promise from onVolumioStart: please update! Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "i2s_dacs"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: I2S DAC not set, start Auto-detection Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "volumiodiscovery"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** For more information see Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 15:48:57 ft1905-febf volumio[18293]: *** WARNING *** For more information see Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** For more information see Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 15:48:57 ft1905-febf node[18293]: *** WARNING *** For more information see Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Discovery: Started advertising with name: FT1905-febf Jun 19 15:48:57 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "outputs"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "albumart"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Plugin example_plugin is not enabled Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "inputs"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "pandora"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Applying required configuration parameters for plugin pandora Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "podcast"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jun 19 15:48:57 ft1905-febf volumio[18293]: info: ControllerPodcast::constructor Jun 19 15:48:57 ft1905-febf volumio[18293]: info: Loading plugin "qobuz"... Jun 19 15:48:57 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jun 19 15:48:58 ft1905-febf volumio[18293]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Loading plugin "tidal"... Jun 19 15:48:58 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338175] ControllerTidalPlugin::constructor Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338177] ControllerTidalPlugin::onVolumioStart Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Loading plugin "updater_comm"... Jun 19 15:48:58 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Plugin mpdemulation is not enabled Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Loading plugin "rest_api"... Jun 19 15:48:58 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Loading plugin "websocket"... Jun 19 15:48:58 ft1905-febf volumio[18293]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jun 19 15:48:58 ft1905-febf volumio[18293]: info: ___________ START PLUGINS ___________ Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338434] CoreMusicLibrary::Adding element Media Servers Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: SSDP Client initialized. Jun 19 15:48:58 ft1905-febf volumio[18293]: Starting initial SSDP search... Jun 19 15:48:58 ft1905-febf volumio[18293]: Discovering on interface: lo Jun 19 15:48:58 ft1905-febf volumio[18293]: Discovering on interface: eth0 Jun 19 15:48:58 ft1905-febf volumio[18293]: Discovering on interface: wlan0 Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338570] CoreMusicLibrary::Adding element Last_100 Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Creating Spotify config file Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338717] CoreMusicLibrary::Adding element Webradio Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Yandex plugin started Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338742] ControllerYandexPlugin::addToBrowseSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338743] CoreMusicLibrary::Adding element Yandex Music Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: error: Plugin yandex does not return adequate promise from onStart: please update! Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338761] CoreMusicLibrary::Adding element Pandora Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338767] ControllerPandora::initializeMQTT Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338769] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338784] CoreMusicLibrary::Adding element Podcast Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338792] CoreMusicLibrary::Adding element Qobuz Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338806] ControllerTidalPlugin::onStart Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338814] ControllerTidalPlugin::addToBrowseSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:48:58 ft1905-febf volumio[18293]: info: [1750319338815] CoreMusicLibrary::Adding element Tidal Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: Loading i18n strings for locale zh Jun 19 15:48:58 ft1905-febf volumio[18293]: Updating browse sources language Jun 19 15:48:58 ft1905-febf volumio[18293]: Cannot find translation for sourceMedia Servers Jun 19 15:48:58 ft1905-febf volumio[18293]: Cannot find translation for sourceYandex Music Jun 19 15:48:58 ft1905-febf volumio[18293]: Cannot find translation for sourcePodcast Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::initPlayerControls Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:58 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 15:48:59 ft1905-febf volumio[18293]: info: BOOT COMPLETED Jun 19 15:48:59 ft1905-febf volumio[18293]: [Metrics] CommandRouter: 10s 804.30ms Jun 19 15:48:59 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 15:48:59 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 19 15:49:01 ft1905-febf socketio[2399]: [2025-06-19 15:49:01] [connect] Successful connection Jun 19 15:49:04 ft1905-febf volumio[18293]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 19 15:49:04 ft1905-febf volumio[18293]: info: CoreCommandRouter::Close All Modals sent Jun 19 15:49:04 ft1905-febf volumio[18293]: info: CoreCommandRouter::Close All Modals sent Jun 19 15:49:04 ft1905-febf volumio[18293]: Express server listening on port 3000 Jun 19 15:49:04 ft1905-febf volumio[18293]: [Metrics] WebUI: 16s 868.35ms Jun 19 15:49:04 ft1905-febf volumio[18293]: (node:18293) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 19 15:49:04 ft1905-febf volumio[18293]: info: [1750319344621] ControllerPandora::validateAndSetAccountOptions Jun 19 15:49:04 ft1905-febf volumio[18293]: error: [1750319344626] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jun 19 15:49:04 ft1905-febf volumio[18293]: info: CoreStateMachine::resetVolumioState Jun 19 15:49:04 ft1905-febf volumio[18293]: info: CoreStateMachine::getcurrentVolume Jun 19 15:49:04 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 15:49:04 ft1905-febf sudo[18408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 15:49:04 ft1905-febf sudo[18408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:49:04 ft1905-febf sudo[18411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 15:49:04 ft1905-febf sudo[18408]: pam_unix(sudo:session): session closed for user root Jun 19 15:49:05 ft1905-febf sudo[18411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 15:49:05 ft1905-febf sudo[18411]: pam_unix(sudo:session): session closed for user root Jun 19 15:49:05 ft1905-febf volumio[18293]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 19 15:49:05 ft1905-febf volumio[18293]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:49:05 ft1905-febf volumio[18293]: DATE: 'Thu, 19 Jun 2025 07:49:04 GMT', Jun 19 15:49:05 ft1905-febf volumio[18293]: EXT: '', Jun 19 15:49:05 ft1905-febf volumio[18293]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:49:05 ft1905-febf volumio[18293]: SERVER: 'eXtensible UPnP agent', Jun 19 15:49:05 ft1905-febf volumio[18293]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:49:05 ft1905-febf volumio[18293]: USN: Jun 19 15:49:05 ft1905-febf volumio[18293]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:49:05 ft1905-febf volumio[18293]: Device IP: 192.168.2.1 Jun 19 15:49:05 ft1905-febf volumio[18293]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:49:05 ft1905-febf volumio[18293]: DATE: 'Thu, 19 Jun 2025 07:49:05 GMT', Jun 19 15:49:05 ft1905-febf volumio[18293]: EXT: '', Jun 19 15:49:05 ft1905-febf volumio[18293]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:49:05 ft1905-febf volumio[18293]: SERVER: 'eXtensible UPnP agent', Jun 19 15:49:05 ft1905-febf volumio[18293]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:49:05 ft1905-febf volumio[18293]: USN: Jun 19 15:49:05 ft1905-febf volumio[18293]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:49:05 ft1905-febf volumio[18293]: Device IP: 192.168.2.1 Jun 19 15:49:05 ft1905-febf volumio[18293]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:49:05 ft1905-febf volumio[18293]: DATE: 'Thu, 19 Jun 2025 07:49:04 GMT', Jun 19 15:49:05 ft1905-febf volumio[18293]: EXT: '', Jun 19 15:49:05 ft1905-febf volumio[18293]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:49:05 ft1905-febf volumio[18293]: SERVER: 'eXtensible UPnP agent', Jun 19 15:49:05 ft1905-febf volumio[18293]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:49:05 ft1905-febf volumio[18293]: USN: Jun 19 15:49:05 ft1905-febf volumio[18293]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:49:05 ft1905-febf volumio[18293]: Device IP: 192.168.2.1 Jun 19 15:49:05 ft1905-febf volumio[18293]: Device response: { 'CACHE-CONTROL': 'max-age=1800', Jun 19 15:49:05 ft1905-febf volumio[18293]: DATE: 'Thu, 19 Jun 2025 07:49:05 GMT', Jun 19 15:49:05 ft1905-febf volumio[18293]: EXT: '', Jun 19 15:49:05 ft1905-febf volumio[18293]: LOCATION: 'http://192.168.2.1:4044/dev.xml', Jun 19 15:49:05 ft1905-febf volumio[18293]: SERVER: 'eXtensible UPnP agent', Jun 19 15:49:05 ft1905-febf volumio[18293]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 19 15:49:05 ft1905-febf volumio[18293]: USN: Jun 19 15:49:05 ft1905-febf volumio[18293]: 'uuid:60bd2fb3-dabe-cb14-c766-0e319b54c29a::urn:schemas-upnp-org:device:MediaServer:1' } Jun 19 15:49:05 ft1905-febf volumio[18293]: Device IP: 192.168.2.1 Jun 19 15:49:05 ft1905-febf volumio[18293]: info: this.stateMachine.currentVolume: null, vol: 0 Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreStateMachine::updateTrackBlock Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CorePlayQueue::getTrackBlock Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 15:49:05 ft1905-febf volumio[18293]: info: MPD Permissions set Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 15:49:05 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 19 15:49:06 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:06 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:06 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:06 ft1905-febf volumio[18433]: Forking 1 albumart workers Jun 19 15:49:06 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:06 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:06 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:06 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:06 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:06 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:06 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:07 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:07 ft1905-febf volumio[18450]: Starting albumart workers Jun 19 15:49:08 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:08 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:08 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:08 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:08 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:08 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:08 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:08 ft1905-febf volumio[18293]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jun 19 15:49:08 ft1905-febf volumio[18293]: sudo: /usr/sbin/i2cdetect: command not found Jun 19 15:49:08 ft1905-febf volumio[18293]: info: Spotify config file written Jun 19 15:49:08 ft1905-febf sudo[18492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jun 19 15:49:08 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:08 ft1905-febf sudo[18492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:49:08 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf systemd[1]: Stopping go-librespot Daemon... Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf systemd[1]: go-librespot-daemon.service: Succeeded. Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf systemd[1]: Stopped go-librespot Daemon. Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf systemd[1]: Started go-librespot Daemon. Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf sudo[18492]: pam_unix(sudo:session): session closed for user root Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf go-librespot[18506]: go-librespot daemon starting... Jun 19 15:49:09 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:09+08:00" level=info msg="running go-librespot 0.2.0" Jun 19 15:49:09 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:09+08:00" level=debug msg="app state loaded" Jun 19 15:49:09 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:09+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:09 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:09 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:10 ft1905-febf volumio[18293]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 19 15:49:10 ft1905-febf volumio[18293]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Jun 19 15:49:10 ft1905-febf volumio[18293]: sendCommand: Command { name: 'search album ""', args: [] } Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:10 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:10 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:10 ft1905-febf volumio[18293]: info: MPD running with PID18369 Jun 19 15:49:10 ft1905-febf volumio[18293]: ,establishing connection Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 15:49:10 ft1905-febf volumio[18293]: info: No need to fix Spotify hosts Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:10 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:10 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:11 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:11 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:11+08:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Jun 19 15:49:11 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:11+08:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 19 15:49:11 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:11+08:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:11 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:11+08:00" level=info msg="zeroconf server listening on port 46503" Jun 19 15:49:11 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:11 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:11 ft1905-febf volumio[18293]: error: updateQueue error: null Jun 19 15:49:11 ft1905-febf volumio[18293]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreStateMachine::setRepeat null single undefined Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreStateMachine::setRandom false Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:11 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 19 15:49:11 ft1905-febf volumio[18293]: info: [pushCmd] {"index":5,"getInfo":0} Jun 19 15:49:11 ft1905-febf volumio[18293]: Upnp client error 2: Error: This socket has been ended by the other party Jun 19 15:49:11 ft1905-febf volumio[18293]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 19 15:49:11 ft1905-febf volumio[18293]: error: updateQueue error: null Jun 19 15:49:11 ft1905-febf volumio[18293]: info: Starting Shairport Sync Jun 19 15:49:11 ft1905-febf sudo[18552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 19 15:49:11 ft1905-febf sudo[18552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:49:11 ft1905-febf volumio[18293]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 19 15:49:11 ft1905-febf volumio[18293]: SPOTIFY: BQBQ5hqRwRA_r0b5O10f-zxXha8TSuSi3SzXz_hqXAKrcU-qVPXkRj_bdR_6w2iO38dGycJSK1rtztE6bapg0Dcl5Ej6RqKGPwL3--rgA8d7_wLCKZapBM7Xok2DlFbDzWtgCGiq4YKGUJs5mr1jtncRyfpcLvpH7LDL-8Yr-QoF3cEXFtbDbb-m2s8piI7YbZ2XHUKaDUkh3i_3oaSLRQGsfRXZmOAvEImuNPHw-uH7kAK-zVQ8XQk2mf1GdWcbl5ZeXvoYXaA3G01Q8xRo-lUQlj0NXiZLePbHNHDgrz2tSCvvZk4 Jun 19 15:49:11 ft1905-febf volumio[18293]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 19 15:49:11 ft1905-febf sudo[18556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 15:49:11 ft1905-febf volumio[18293]: info: New Spotify access token = BQBQ5hqRwRA_r0b5O10f-zxXha8TSuSi3SzXz_hqXAKrcU-qVPXkRj_bdR_6w2iO38dGycJSK1rtztE6bapg0Dcl5Ej6RqKGPwL3--rgA8d7_wLCKZapBM7Xok2DlFbDzWtgCGiq4YKGUJs5mr1jtncRyfpcLvpH7LDL-8Yr-QoF3cEXFtbDbb-m2s8piI7YbZ2XHUKaDUkh3i_3oaSLRQGsfRXZmOAvEImuNPHw-uH7kAK-zVQ8XQk2mf1GdWcbl5ZeXvoYXaA3G01Q8xRo-lUQlj0NXiZLePbHNHDgrz2tSCvvZk4 Jun 19 15:49:11 ft1905-febf volumio[18293]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 19 15:49:11 ft1905-febf sudo[18556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 15:49:12 ft1905-febf sudo[18552]: pam_unix(sudo:session): session closed for user root Jun 19 15:49:12 ft1905-febf systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 19 15:49:12 ft1905-febf systemd[1]: shairport-sync.service: Succeeded. Jun 19 15:49:12 ft1905-febf systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 19 15:49:12 ft1905-febf systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 19 15:49:12 ft1905-febf sudo[18556]: pam_unix(sudo:session): session closed for user root Jun 19 15:49:12 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:12+08:00" level=debug msg="obtained new client token: AABcd+ELAEZjSQAV32h7DDtlTTXqbTfwA7CBcYdQZUQBsPkLW0uhyztsOZlzbg9vktVJ8YSS3mu8w2Xf/6rLgh1jvx6i7OE1VAFxjuOFlwdctAYhDrmVFQJsjGGiTH35yTNee87n9FAX3VPB45s/4CYA31z3bH8c2XURm+jAWdqhh16Ro4sMN+QSdZCHwLyImOBHVih4eYjPO8qISRTypp5aZeggGi6PzK5TRjkrOf0hVM+HTCWTXLVE" Jun 19 15:49:12 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:12+08:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioGetQueue Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CoreStateMachine::getQueue Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CorePlayQueue::getQueue Jun 19 15:49:12 ft1905-febf volumio[18293]: info: Listing playlists Jun 19 15:49:12 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: info: Upmpdcli Daemon Started Jun 19 15:49:12 ft1905-febf volumio[18293]: Discovery: adding 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 19 15:49:12 ft1905-febf volumio[18293]: info: mDNS: Found device FT1905-febf Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: Discovery: this is already registered, 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 19 15:49:12 ft1905-febf volumio[18293]: info: mDNS: Found device FT1905-febf Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: info: Shairport-Sync Started Jun 19 15:49:12 ft1905-febf volumio[18293]: Error adding Membership: Error: addMembership EINVAL Jun 19 15:49:12 ft1905-febf volumio[18293]: Discovery: this is already registered, 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 19 15:49:12 ft1905-febf volumio[18293]: info: mDNS: Found device FT1905-febf Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:12 ft1905-febf volumio[18293]: error: Could not retrieve plugin audio_interface multiroom Jun 19 15:49:13 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:13+08:00" level=debug msg="completed keyexchange" Jun 19 15:49:13 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:13+08:00" level=debug msg="completed challenge" Jun 19 15:49:13 ft1905-febf volumio[18293]: SPOTIFY: User informations: {"country":"NG","display_name":"surui2","email":"surui2@126.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dobbykadpjqbg53ytmzna3bo7u"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dobbykadpjqbg53ytmzna3bo7u","id":"31dobbykadpjqbg53ytmzna3bo7u","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee850b8b915c295ca470ca2440a0","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b820b8b915c295ca470ca2440a0","width":64}],"product":"premium","type":"user","uri":"spotify:user:31dobbykadpjqbg53ytmzna3bo7u"} Jun 19 15:49:13 ft1905-febf volumio[18293]: info: Spotify Successfully logged in Jun 19 15:49:13 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 15:49:13 ft1905-febf volumio[18293]: info: [1750319353956] CoreMusicLibrary::Adding element Spotify Jun 19 15:49:13 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 15:49:14 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:14+08:00" level=info msg="authenticated AP as 31dobbykadpjqbg53ytmzna3bo7u" Jun 19 15:49:14 ft1905-febf volumio[18293]: info: go-librespot daemon successfully initialized Jun 19 15:49:15 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:15+08:00" level=info msg="authenticated Login5 as 31dobbykadpjqbg53ytmzna3bo7u" Jun 19 15:49:15 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:15+08:00" level=debug msg="initializing zeroconf session, username: 31dobbykadpjqbg53ytmzna3bo7u" Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioReplaceAndPlayItems Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::ClearQueue Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::stop Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::serviceStop Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::serviceStop trackBlock undefined Jun 19 15:49:15 ft1905-febf volumio[18293]: info: ControllerMpd::stop Jun 19 15:49:15 ft1905-febf volumio[18293]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CorePlayQueue::clearPlayQueue Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CorePlayQueue::saveQueue Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioPushQueue Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::addQueueItems Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CorePlayQueue::addQueueItems Jun 19 15:49:15 ft1905-febf volumio[18293]: info: Exploding uri yandex/track/110601854 in service yandex Jun 19 15:49:15 ft1905-febf volumio[18293]: info: [1750319355498] ControllerYandexPlugin::explodeUri Jun 19 15:49:15 ft1905-febf volumio[18293]: info: explodeUri uri: yandex/track/110601854 Jun 19 15:49:15 ft1905-febf volumio[18293]: info: Exploding uri yandex/track/139590540 in service yandex Jun 19 15:49:15 ft1905-febf volumio[18293]: info: [1750319355518] ControllerYandexPlugin::explodeUri Jun 19 15:49:15 ft1905-febf volumio[18293]: info: explodeUri uri: yandex/track/139590540 Jun 19 15:49:15 ft1905-febf volumio[18293]: sendCommand: Command { name: 'stop', args: [] } Jun 19 15:49:15 ft1905-febf volumio[18293]: info: sendMpdCommand stop took 97 milliseconds Jun 19 15:49:15 ft1905-febf volumio[18293]: info: ControllerMpd::getState Jun 19 15:49:15 ft1905-febf volumio[18293]: verbose: ControllerMpd::sendMpdCommand status Jun 19 15:49:15 ft1905-febf volumio[18293]: sendCommand: Command { name: 'status', args: [] } Jun 19 15:49:15 ft1905-febf volumio[18293]: info: sendMpdCommand status took 5 milliseconds Jun 19 15:49:15 ft1905-febf volumio[18293]: verbose: ControllerMpd::parseState Jun 19 15:49:15 ft1905-febf volumio[18293]: info: ControllerMpd::pushState Jun 19 15:49:15 ft1905-febf volumio[18293]: this.isVolatile: false, stateService.status: stop Jun 19 15:49:15 ft1905-febf volumio[18293]: this.volatileState: {} Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:15 ft1905-febf volumio[18293]: 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,"service":"mpd"} Jun 19 15:49:15 ft1905-febf volumio[18293]: verbose: CURRENT POSITION 0 Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::syncState stateService stop Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::syncState currentStatus stop Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:15 ft1905-febf volumio[18293]: info: No code Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreStateMachine::pushState Jun 19 15:49:15 ft1905-febf volumio[18293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="dealer connection opened" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=trace msg="starting accesspoint recv loop" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=trace msg="starting dealer recv loop" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=trace msg="received accesspoint ping" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=debug msg="received connection id: OGM4OTYzMDctMjZjYy00MDcwLThhMGMtMTc4OTcxNDdlNTZiK2RlYWxlcit0Y3A6Ly8wYWNiZjg4MS5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArRjM0MThFOTU0QjJEMTU5Q0QzNTc5MTAxRkFBMTAwOENCMTk3MkIwODZFRUMyMDg1Mzk1RTg1RUREOTM2Qzk1Ng==" Jun 19 15:49:16 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:16+08:00" level=trace msg="received accesspoint pong ack" Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CorePlayQueue::saveQueue Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioPushQueue Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::updateTrackBlock Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CorePlayQueue::getTrackBlock Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreCommandRouter::volumioPlay Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: verbose: UNSET VOLATILE undefined Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::play index 0 Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::stop Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::serviceStop Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::serviceStop trackBlock {"uri":"yandex/track/110601854","service":"yandex","type":"song","albumart":"https://avatars.yandex.net/get-music-content/8316823/165611b5.a.24594299-1/200x200","artist":"Ручной Рептилоид","album":null,"name":"Я хочу быть 2д","title":"Я хочу быть 2д","duration":null} Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreCommandRouter::serviceStop Jun 19 15:49:17 ft1905-febf volumio[18293]: info: [1750319357055] ControllerYandexPlugin::stop Jun 19 15:49:17 ft1905-febf volumio[18293]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 15:49:17 ft1905-febf volumio[18293]: sendCommand: Command { name: 'stop', args: [] } Jun 19 15:49:17 ft1905-febf volumio[18293]: info: sendMpdCommand stop took 9 milliseconds Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::play index undefined Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 15:49:17 ft1905-febf volumio[18293]: !! !! Jun 19 15:49:17 ft1905-febf volumio[18293]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CoreStateMachine::startPlaybackTimer Jun 19 15:49:17 ft1905-febf volumio[18293]: info: [1750319357074] ControllerYandexPlugin::clearAddPlayTrack Jun 19 15:49:17 ft1905-febf volumio[18293]: info: clearAddPlayTrack track: {"uri":"yandex/track/110601854","service":"yandex","type":"song","albumart":"https://avatars.yandex.net/get-music-content/8316823/165611b5.a.24594299-1/200x200","artist":"Ручной Рептилоид","album":null,"name":"Я хочу быть 2д","title":"Я хочу быть 2д","duration":null} Jun 19 15:49:17 ft1905-febf volumio[18293]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 15:49:17 ft1905-febf volumio[18293]: sendCommand: Command { name: 'stop', args: [] } Jun 19 15:49:17 ft1905-febf volumio[18293]: info: sendMpdCommand stop took 12 milliseconds Jun 19 15:49:17 ft1905-febf volumio[18293]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 15:49:17 ft1905-febf volumio[18293]: sendCommand: Command { name: 'clear', args: [] } Jun 19 15:49:17 ft1905-febf volumio[18293]: info: Jun 19 15:49:17 ft1905-febf volumio[18293]: ---------------------------- MPD announces system playlist update Jun 19 15:49:17 ft1905-febf volumio[18293]: info: Ignoring MPD Status Update Jun 19 15:49:17 ft1905-febf volumio[18293]: info: sendMpdCommand clear took 8 milliseconds Jun 19 15:49:17 ft1905-febf volumio[18293]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 19 15:49:17 ft1905-febf volumio[18293]: error: updateQueue error: null Jun 19 15:49:17 ft1905-febf volumio[18293]: info: ------------------------------ 23ms Jun 19 15:49:17 ft1905-febf volumio[18293]: info: Jun 19 15:49:17 ft1905-febf volumio[18293]: ---------------------------- MPD announces system playlist update Jun 19 15:49:17 ft1905-febf volumio[18293]: info: Ignoring MPD Status Update Jun 19 15:49:17 ft1905-febf volumio[18293]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 19 15:49:17 ft1905-febf volumio[18293]: error: updateQueue error: null Jun 19 15:49:17 ft1905-febf volumio[18293]: info: ------------------------------ 7ms Jun 19 15:49:17 ft1905-febf volumio[18293]: info: CorePlayQueue::getTrack 1 Jun 19 15:49:17 ft1905-febf volumio[18293]: info: Prefetching next song Jun 19 15:49:17 ft1905-febf volumio[18293]: info: [1750319357332] ControllerYandexPlugin::prefetch Jun 19 15:49:17 ft1905-febf volumio[18293]: info: prefetch track: {"uri":"yandex/track/139590540","service":"yandex","type":"song","albumart":"https://avatars.yandex.net/get-music-content/15401259/f726acdc.a.36805749-1/200x200","artist":"KRISTIEE","album":null,"name":"Dirty","title":"Dirty","duration":null} Jun 19 15:49:17 ft1905-febf volumio[18293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 15:49:17 ft1905-febf volumio[18293]: TypeError: this.trackUrl(...).then(...).fail is not a function Jun 19 15:49:17 ft1905-febf volumio[18293]: at ControllerYandexPlugin.prefetch (webpack://Volumio2/./app/plugins/music_service/yandex/index.js?:208:14) Jun 19 15:49:17 ft1905-febf volumio[18293]: at CoreStateMachine.increasePlaybackTimer (webpack://Volumio2/./app/statemachine.js?:437:18) Jun 19 15:49:17 ft1905-febf volumio[18293]: at ontimeout (timers.js:436:11) Jun 19 15:49:17 ft1905-febf volumio[18293]: at tryOnTimeout (timers.js:300:5) Jun 19 15:49:17 ft1905-febf volumio[18293]: at listOnTimeout (timers.js:263:5) Jun 19 15:49:17 ft1905-febf volumio[18293]: at Timer.processTimers (timers.js:223:10) Jun 19 15:49:17 ft1905-febf volumio[18293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 15:49:17 ft1905-febf go-librespot[18513]: time="2025-06-19T15:49:17+08:00" level=debug msg="put connect state because NEW_DEVICE" Jun 19 15:49:17 ft1905-febf socketio[2399]: [2025-06-19 15:49:17] [connect] Successful connection Jun 19 15:49:18 ft1905-febf sudo[18605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 15:48 Jun 19 15:49:18 ft1905-febf sudo[18605]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.4.1.157 VOLUMIO_BUILD_DATE=2025-06-17 19:19:17 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system