-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Wed 2025-05-28 16:45:17 CST. -- May 28 16:44:06 ft1905-febf volumio[28419]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 28 16:44:06 ft1905-febf volumio[28419]: info: [1748421846840] ControllerTidalPlugin::handleBrowseUri May 28 16:44:06 ft1905-febf volumio[28419]: error: Failed to execute browseSource: TypeError: self.parseBrowseSource(...).then(...).then(...).fail is not a function May 28 16:44:06 ft1905-febf volumio[28419]: /pages/single-module-page/ae223310-a4c2-4568-a770-ffef70344441/4/889e7c2a-e3be-472b-9801-bf6b40655050/1: load from cache May 28 16:44:06 ft1905-febf volumio[28419]: (node:28419) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'items' of undefined May 28 16:44:06 ft1905-febf volumio[28419]: at TidalAPI.resultToTracks (webpack://Volumio2/./app/plugins/music_service/tidal/tidalapi/lib/client.js?:212:22) May 28 16:44:06 ft1905-febf volumio[28419]: at getArtistTracks (webpack://Volumio2/./app/plugins/music_service/tidal/tidalapi/lib/client.js?:191:28) May 28 16:44:06 ft1905-febf volumio[28419]: at TidalAPI._baseRequest (webpack://Volumio2/./app/plugins/music_service/tidal/tidalapi/lib/client.js?:431:7) May 28 16:44:06 ft1905-febf volumio[28419]: at process._tickCallback (internal/process/next_tick.js:68:7) May 28 16:44:06 ft1905-febf volumio[28419]: (node:28419) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 6) May 28 16:44:30 ft1905-febf volumio[28419]: Device response: { LOCATION: May 28 16:44:30 ft1905-febf volumio[28419]: 'http://192.168.100.112:9365/MediaServer/DeviceDescription.xml', May 28 16:44:30 ft1905-febf volumio[28419]: 'CONFIGID.UPNP.ORG': '0', May 28 16:44:30 ft1905-febf volumio[28419]: SERVER: May 28 16:44:30 ft1905-febf volumio[28419]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', May 28 16:44:30 ft1905-febf volumio[28419]: USN: May 28 16:44:30 ft1905-febf volumio[28419]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', May 28 16:44:30 ft1905-febf volumio[28419]: EXT: '', May 28 16:44:30 ft1905-febf volumio[28419]: 'BOOTID.UPNP.ORG': '1748229974', May 28 16:44:30 ft1905-febf volumio[28419]: DATE: 'Wed, 28 May 2025 08:44:29 GMT', May 28 16:44:30 ft1905-febf volumio[28419]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', May 28 16:44:30 ft1905-febf volumio[28419]: 'CACHE-CONTROL': 'max-age=1800' } May 28 16:44:30 ft1905-febf volumio[28419]: Device IP: 192.168.100.112 May 28 16:44:30 ft1905-febf volumio[28419]: Device response: { 'BOOTID.UPNP.ORG': '1748229974', May 28 16:44:30 ft1905-febf volumio[28419]: 'CONFIGID.UPNP.ORG': '0', May 28 16:44:30 ft1905-febf volumio[28419]: LOCATION: May 28 16:44:30 ft1905-febf volumio[28419]: 'http://192.168.100.112:9365/MediaServer/DeviceDescription.xml', May 28 16:44:30 ft1905-febf volumio[28419]: USN: May 28 16:44:30 ft1905-febf volumio[28419]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', May 28 16:44:30 ft1905-febf volumio[28419]: EXT: '', May 28 16:44:30 ft1905-febf volumio[28419]: SERVER: May 28 16:44:30 ft1905-febf volumio[28419]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', May 28 16:44:30 ft1905-febf volumio[28419]: DATE: 'Wed, 28 May 2025 08:44:29 GMT', May 28 16:44:30 ft1905-febf volumio[28419]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', May 28 16:44:30 ft1905-febf volumio[28419]: 'CACHE-CONTROL': 'max-age=1800' } May 28 16:44:30 ft1905-febf volumio[28419]: Device IP: 192.168.100.112 May 28 16:44:47 ft1905-febf sshd[3971]: rexec line 6: Deprecated option UsePrivilegeSeparation May 28 16:44:47 ft1905-febf sshd[3971]: rexec line 7: Deprecated option KeyRegenerationInterval May 28 16:44:47 ft1905-febf sshd[3971]: rexec line 8: Deprecated option ServerKeyBits May 28 16:44:47 ft1905-febf sshd[3971]: rexec line 14: Deprecated option RSAAuthentication May 28 16:44:47 ft1905-febf sshd[3971]: rexec line 17: Deprecated option RhostsRSAAuthentication May 28 16:44:47 ft1905-febf sshd[3971]: reprocess config line 14: Deprecated option RSAAuthentication May 28 16:44:47 ft1905-febf sshd[3971]: reprocess config line 17: Deprecated option RhostsRSAAuthentication May 28 16:44:47 ft1905-febf sshd[3971]: Accepted password for volumio from 192.168.100.104 port 60164 ssh2 May 28 16:44:47 ft1905-febf sshd[3971]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 28 16:44:47 ft1905-febf systemd-logind[2268]: New session c23 of user volumio. May 28 16:44:47 ft1905-febf systemd[1]: Started Session c23 of user volumio. May 28 16:44:49 ft1905-febf sshd[3988]: Received disconnect from 192.168.100.104 port 60164:11: May 28 16:44:49 ft1905-febf sshd[3988]: Disconnected from user volumio 192.168.100.104 port 60164 May 28 16:44:50 ft1905-febf sshd[3971]: pam_unix(sshd:session): session closed for user volumio May 28 16:44:50 ft1905-febf sshd[4001]: rexec line 6: Deprecated option UsePrivilegeSeparation May 28 16:44:50 ft1905-febf sshd[4001]: rexec line 7: Deprecated option KeyRegenerationInterval May 28 16:44:50 ft1905-febf sshd[4001]: rexec line 8: Deprecated option ServerKeyBits May 28 16:44:50 ft1905-febf sshd[4001]: rexec line 14: Deprecated option RSAAuthentication May 28 16:44:50 ft1905-febf sshd[4001]: rexec line 17: Deprecated option RhostsRSAAuthentication May 28 16:44:50 ft1905-febf systemd-logind[2268]: Session c23 logged out. Waiting for processes to exit. May 28 16:44:50 ft1905-febf systemd[1]: session-c23.scope: Succeeded. May 28 16:44:50 ft1905-febf systemd-logind[2268]: Removed session c23. May 28 16:44:50 ft1905-febf sshd[4001]: reprocess config line 14: Deprecated option RSAAuthentication May 28 16:44:50 ft1905-febf sshd[4001]: reprocess config line 17: Deprecated option RhostsRSAAuthentication May 28 16:44:50 ft1905-febf sshd[4001]: Accepted password for volumio from 192.168.100.104 port 60166 ssh2 May 28 16:44:50 ft1905-febf sshd[4001]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 28 16:44:50 ft1905-febf systemd-logind[2268]: New session c24 of user volumio. May 28 16:44:50 ft1905-febf systemd[1]: Started Session c24 of user volumio. May 28 16:44:51 ft1905-febf sudo[4024]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop volumio.service May 28 16:44:51 ft1905-febf sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:51 ft1905-febf systemd[1]: Stopping Volumio Backend Module... May 28 16:44:51 ft1905-febf socketio[2415]: [2025-05-28 16:44:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 28 16:44:51 ft1905-febf socketio[2415]: [2025-05-28 16:44:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 28 16:44:51 ft1905-febf systemd[1]: volumio.service: Succeeded. May 28 16:44:51 ft1905-febf systemd[1]: Stopped Volumio Backend Module. May 28 16:44:51 ft1905-febf sudo[4024]: pam_unix(sudo:session): session closed for user root May 28 16:44:51 ft1905-febf sudo[4034]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start volumio.service May 28 16:44:51 ft1905-febf sudo[4034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:51 ft1905-febf systemd[1]: Started dynamicswap service. May 28 16:44:51 ft1905-febf systemd[1]: Started Volumio Backend Module. May 28 16:44:51 ft1905-febf sudo[4034]: pam_unix(sudo:session): session closed for user root May 28 16:44:51 ft1905-febf systemd[1]: dynamicswap.service: Succeeded. May 28 16:44:51 ft1905-febf sshd[4021]: Received disconnect from 192.168.100.104 port 60166:11: May 28 16:44:51 ft1905-febf sshd[4021]: Disconnected from user volumio 192.168.100.104 port 60166 May 28 16:44:51 ft1905-febf sshd[4001]: pam_unix(sshd:session): session closed for user volumio May 28 16:44:51 ft1905-febf systemd[1]: session-c24.scope: Succeeded. May 28 16:44:51 ft1905-febf systemd-logind[2268]: Session c24 logged out. Waiting for processes to exit. May 28 16:44:51 ft1905-febf systemd-logind[2268]: Removed session c24. May 28 16:44:55 ft1905-febf volumio[4041]: info: ------------------------------------------- May 28 16:44:55 ft1905-febf volumio[4041]: info: ----- Volumio2 ---- May 28 16:44:55 ft1905-febf volumio[4041]: info: ------------------------------------------- May 28 16:44:55 ft1905-febf volumio[4041]: info: ----- System startup ---- May 28 16:44:55 ft1905-febf volumio[4041]: info: ------------------------------------------- May 28 16:44:56 ft1905-febf socketio[2415]: [2025-05-28 16:44:56] [connect] Successful connection May 28 16:44:56 ft1905-febf volumio[4041]: info: Found new core plugin music_service/youtube2. Adding it May 28 16:44:56 ft1905-febf volumio[4041]: info: Configured plugin music_service/youtube2 cannot be loaded. Removing from configuration May 28 16:44:57 ft1905-febf volumio[4041]: info: Plugin folders cleanup May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning into folder /volumio/app/plugins/ May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category audio_interface May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category miscellanea May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category music_service May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category plugins.json May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category system_controller May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning category user_interface May 28 16:44:57 ft1905-febf volumio[4041]: info: Scanning into folder /data/plugins/ May 28 16:44:57 ft1905-febf volumio[4041]: info: Plugin folders cleanup completed May 28 16:44:57 ft1905-febf volumio[4041]: info: ------------------------------------------- May 28 16:44:57 ft1905-febf volumio[4041]: info: ----- Core plugins startup ---- May 28 16:44:57 ft1905-febf volumio[4041]: info: ------------------------------------------- May 28 16:44:57 ft1905-febf volumio[4041]: info: Loading plugins from folder /volumio/app/plugins/ May 28 16:44:57 ft1905-febf volumio[4041]: info: Adding plugin upnp to MyMusic Plugins May 28 16:44:57 ft1905-febf volumio[4041]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 16:44:57 ft1905-febf volumio[4041]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 16:44:57 ft1905-febf volumio[4041]: info: Loading plugins from folder /data/plugins/ May 28 16:44:57 ft1905-febf volumio[4041]: info: Loading plugin "system"... May 28 16:44:57 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/system/index.js May 28 16:44:57 ft1905-febf volumio[4041]: info: Loading plugin "appearance"... May 28 16:44:57 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js May 28 16:44:57 ft1905-febf volumio[4041]: info: Loading plugin "network"... May 28 16:44:57 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/network/index.js May 28 16:44:57 ft1905-febf volumio[4041]: info: Refreshing Cached IP Addresses May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "services"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/services/index.js May 28 16:44:58 ft1905-febf sudo[4073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "alsa_controller"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js May 28 16:44:58 ft1905-febf sudo[4073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:58 ft1905-febf sudo[4075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 16:44:58 ft1905-febf volumio[4041]: info: Applying required configuration parameters for plugin alsa_controller May 28 16:44:58 ft1905-febf sudo[4073]: pam_unix(sudo:session): session closed for user root May 28 16:44:58 ft1905-febf sudo[4075]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:58 ft1905-febf sudo[4075]: pam_unix(sudo:session): session closed for user root May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "wizard"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "volumio_command_line_client"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js May 28 16:44:58 ft1905-febf volumio[4041]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template May 28 16:44:58 ft1905-febf volumio[4041]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template May 28 16:44:58 ft1905-febf volumio[4041]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template May 28 16:44:58 ft1905-febf volumio[4041]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template May 28 16:44:58 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "upnp"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js May 28 16:44:58 ft1905-febf volumio[4041]: info: [1748421898732] Starting Upmpd Daemon May 28 16:44:58 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "my_music"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js May 28 16:44:58 ft1905-febf volumio[4041]: info: Loading plugin "mpd"... May 28 16:44:58 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/mpd/index.js May 28 16:44:59 ft1905-febf volumio[4041]: info: Creating MPD Configuration file May 28 16:44:59 ft1905-febf sudo[4101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 16:44:59 ft1905-febf volumio[4041]: info: Loading plugin "upnp_browser"... May 28 16:44:59 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js May 28 16:44:59 ft1905-febf sudo[4101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:59 ft1905-febf sudo[4101]: pam_unix(sudo:session): session closed for user root May 28 16:44:59 ft1905-febf sudo[4107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 16:44:59 ft1905-febf sudo[4107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:44:59 ft1905-febf systemd[1]: Stopping Music Player Daemon... May 28 16:45:00 ft1905-febf systemd[1]: mpd.service: Succeeded. May 28 16:45:00 ft1905-febf systemd[1]: Stopped Music Player Daemon. May 28 16:45:00 ft1905-febf systemd[1]: Starting Music Player Daemon... May 28 16:45:00 ft1905-febf volumio[4041]: info: Loading plugin "networkfs"... May 28 16:45:00 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js May 28 16:45:00 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 16:45:00 ft1905-febf volumio[4041]: info: Loading plugin "alarm-clock"... May 28 16:45:00 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js May 28 16:45:00 ft1905-febf volumio[4041]: info: Loading plugin "airplay_emulation"... May 28 16:45:00 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js May 28 16:45:00 ft1905-febf volumio[4041]: 执行命令出错: ReferenceError: execSync is not defined May 28 16:45:00 ft1905-febf volumio[4041]: info: Starting Shairport Sync May 28 16:45:00 ft1905-febf volumio[4041]: info: Loading plugin "last_100"... May 28 16:45:00 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/last_100/index.js May 28 16:45:00 ft1905-febf volumio[4041]: info: Loading plugin "webradio"... May 28 16:45:00 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/webradio/index.js May 28 16:45:03 ft1905-febf volumio[4041]: info: Loading plugin "i2s_dacs"... May 28 16:45:03 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js May 28 16:45:03 ft1905-febf volumio[4041]: info: I2S DAC not set, start Auto-detection May 28 16:45:03 ft1905-febf volumio[4041]: info: Loading plugin "volumiodiscovery"... May 28 16:45:03 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** For more information see May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 16:45:03 ft1905-febf volumio[4041]: *** WARNING *** For more information see May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** For more information see May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 16:45:03 ft1905-febf node[4041]: *** WARNING *** For more information see May 28 16:45:03 ft1905-febf volumio[4041]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 16:45:03 ft1905-febf volumio[4041]: info: Discovery: Started advertising with name: FT1905-febf May 28 16:45:03 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 16:45:03 ft1905-febf volumio[4041]: info: Loading plugin "outputs"... May 28 16:45:03 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js May 28 16:45:03 ft1905-febf volumio[4041]: info: Loading plugin "albumart"... May 28 16:45:03 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js May 28 16:45:03 ft1905-febf volumio[4041]: info: Plugin example_plugin is not enabled May 28 16:45:03 ft1905-febf volumio[4041]: info: Loading plugin "inputs"... May 28 16:45:03 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/inputs/index.js May 28 16:45:04 ft1905-febf volumio[4041]: info: Loading plugin "pandora"... May 28 16:45:04 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/pandora/index.js May 28 16:45:04 ft1905-febf mpd[4119]: May 28 16:45 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 16:45:04 ft1905-febf mpd[4119]: May 28 16:45 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 28 16:45:04 ft1905-febf mpd[4119]: May 28 16:45 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 28 16:45:04 ft1905-febf volumio[4041]: info: Applying required configuration parameters for plugin pandora May 28 16:45:04 ft1905-febf volumio[4041]: info: Loading plugin "podcast"... May 28 16:45:04 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/podcast/index.js May 28 16:45:04 ft1905-febf volumio[4041]: info: ControllerPodcast::constructor May 28 16:45:04 ft1905-febf volumio[4041]: info: Loading plugin "qobuz"... May 28 16:45:04 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js May 28 16:45:05 ft1905-febf volumio[4041]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! May 28 16:45:05 ft1905-febf volumio[4041]: info: Loading plugin "tidal"... May 28 16:45:05 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/tidal/index.js May 28 16:45:06 ft1905-febf volumio[4041]: info: [1748421906187] ControllerTidalPlugin::constructor May 28 16:45:06 ft1905-febf volumio[4041]: info: [1748421906190] ControllerTidalPlugin::onVolumioStart May 28 16:45:06 ft1905-febf volumio[4041]: info: Loading plugin "volspotconnect2"... May 28 16:45:06 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/music_service/volspotconnect2/index.js May 28 16:45:06 ft1905-febf volumio[4041]: info: Applying required configuration parameters for plugin volspotconnect2 May 28 16:45:06 ft1905-febf volumio[4041]: [SpotifyConnect2] Creating VLS config file May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:06 ft1905-febf volumio[4041]: [SpotifyConnect2] mixname: SoftMaster May 28 16:45:06 ft1905-febf volumio[4041]: [SpotifyConnect2] <> or Mixer found, using softvol May 28 16:45:06 ft1905-febf volumio[4041]: [SpotifyConnect2] initvolstr: 30 May 28 16:45:06 ft1905-febf volumio[4041]: info: Loading plugin "updater_comm"... May 28 16:45:06 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js May 28 16:45:06 ft1905-febf volumio[4041]: info: Plugin mpdemulation is not enabled May 28 16:45:06 ft1905-febf volumio[4041]: info: Loading plugin "rest_api"... May 28 16:45:06 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js May 28 16:45:06 ft1905-febf volumio[4041]: info: Loading plugin "websocket"... May 28 16:45:06 ft1905-febf volumio[4041]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js May 28 16:45:06 ft1905-febf volumio[4041]: info: ___________ START PLUGINS ___________ May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:06 ft1905-febf volumio[4041]: info: [1748421906906] CoreMusicLibrary::Adding element Media Servers May 28 16:45:06 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:06 ft1905-febf volumio[4041]: SSDP Client initialized. May 28 16:45:06 ft1905-febf volumio[4041]: Starting initial SSDP search... May 28 16:45:06 ft1905-febf volumio[4041]: Discovering on interface: lo May 28 16:45:06 ft1905-febf volumio[4041]: Discovering on interface: eth0 May 28 16:45:06 ft1905-febf volumio[4041]: Discovering on interface: wlan0 May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:07 ft1905-febf volumio[4041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907130] CoreMusicLibrary::Adding element Last_100 May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907155] CoreMusicLibrary::Adding element Webradio May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907242] CoreMusicLibrary::Adding element Pandora May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907261] ControllerPandora::initializeMQTT May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907265] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907316] CoreMusicLibrary::Adding element Podcast May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907337] CoreMusicLibrary::Adding element Qobuz May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907378] ControllerTidalPlugin::onStart May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907401] ControllerTidalPlugin::addToBrowseSources May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 16:45:07 ft1905-febf volumio[4041]: info: [1748421907404] CoreMusicLibrary::Adding element Tidal May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] { token_type: { data: 'Bearer' }, May 28 16:45:07 ft1905-febf volumio[4041]: session_id: { data: '5f69583e-2020-48de-a6f7-0e901eedc01d' }, May 28 16:45:07 ft1905-febf volumio[4041]: access_token: May 28 16:45:07 ft1905-febf volumio[4041]: { data: May 28 16:45:07 ft1905-febf volumio[4041]: 'eyJraWQiOiJ2OU1GbFhqWSIsImFsZyI6IkVTMjU2In0.eyJ0eXBlIjoibzJfYWNjZXNzIiwidWlkIjoyMDM4Mzg1NjQsInNjb3BlIjoicl91c3Igd191c3Igd19zdWIiLCJnVmVyIjowLCJzVmVyIjowLCJjaWQiOjMyMzUsImV4cCI6MTc0OTAyNjMyOCwic2lkIjoiNWY2OTU4M2UtMjAyMC00OGRlLWE2ZjctMGU5MDFlZWRjMDFkIiwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.4DPnjSKUVlqQGhi0ucBQHT_ZgwuiNzF9Xgr_TbcuyMvbARjpGI2HZtiFQ08RLRCsxin5RtgHSf21vwOb3hTOOg' }, May 28 16:45:07 ft1905-febf volumio[4041]: refresh_token: May 28 16:45:07 ft1905-febf volumio[4041]: { data: May 28 16:45:07 ft1905-febf volumio[4041]: 'eyJraWQiOiJoUzFKYTdVMCIsImFsZyI6IkVTNTEyIn0.eyJ0eXBlIjoibzJfcmVmcmVzaCIsInVpZCI6MjAzODM4NTY0LCJzY29wZSI6InJfdXNyIHdfdXNyIHdfc3ViIiwiY2lkIjozMjM1LCJzVmVyIjowLCJnVmVyIjowLCJpc3MiOiJodHRwczovL2F1dGgudGlkYWwuY29tL3YxIn0.AVDKQv1usUEOrWrDEACXxRRbtxBCn6-2OnEYumixElFHRixp8J49Np-BptDKeS3YaREEENaJir2a-CdJakASB9fbAeaNqA60byflpSHao09tJ-XXshlgNedTFusXLfAYVMtzuT2Sr5h1hAXXnZC1bnXQAYFNc8djymYfP6X0pkNloRCg' }, May 28 16:45:07 ft1905-febf volumio[4041]: is_pkce: { data: false }, May 28 16:45:07 ft1905-febf volumio[4041]: expiry_time: { data: 1749026328344 } } May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] Bearer eyJraWQiOiJ2OU1GbFhqWSIsImFsZyI6IkVTMjU2In0.eyJ0eXBlIjoibzJfYWNjZXNzIiwidWlkIjoyMDM4Mzg1NjQsInNjb3BlIjoicl91c3Igd191c3Igd19zdWIiLCJnVmVyIjowLCJzVmVyIjowLCJjaWQiOjMyMzUsImV4cCI6MTc0OTAyNjMyOCwic2lkIjoiNWY2OTU4M2UtMjAyMC00OGRlLWE2ZjctMGU5MDFlZWRjMDFkIiwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.4DPnjSKUVlqQGhi0ucBQHT_ZgwuiNzF9Xgr_TbcuyMvbARjpGI2HZtiFQ08RLRCsxin5RtgHSf21vwOb3hTOOg May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] ===================== params ============== May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] uri https://api.tidal.com/v1/sessions?limit=1000 May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] method GET May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] params {"limit":1000} May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] data {} May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] headers {"User-Agent":"Mozilla/5.0 (Linux; Android 12; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/91.0.4472.114 Safari/537.36","authorization":"Bearer eyJraWQiOiJ2OU1GbFhqWSIsImFsZyI6IkVTMjU2In0.eyJ0eXBlIjoibzJfYWNjZXNzIiwidWlkIjoyMDM4Mzg1NjQsInNjb3BlIjoicl91c3Igd191c3Igd19zdWIiLCJnVmVyIjowLCJzVmVyIjowLCJjaWQiOjMyMzUsImV4cCI6MTc0OTAyNjMyOCwic2lkIjoiNWY2OTU4M2UtMjAyMC00OGRlLWE2ZjctMGU5MDFlZWRjMDFkIiwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.4DPnjSKUVlqQGhi0ucBQHT_ZgwuiNzF9Xgr_TbcuyMvbARjpGI2HZtiFQ08RLRCsxin5RtgHSf21vwOb3hTOOg"} May 28 16:45:07 ft1905-febf volumio[4041]: [Tidal] ===================== end params ============== May 28 16:45:07 ft1905-febf volumio[4041]: [SpotifyConnect2] Creating VLS config file May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:07 ft1905-febf volumio[4041]: [SpotifyConnect2] mixname: SoftMaster May 28 16:45:07 ft1905-febf volumio[4041]: [SpotifyConnect2] <> or Mixer found, using softvol May 28 16:45:07 ft1905-febf volumio[4041]: [SpotifyConnect2] initvolstr: 30 May 28 16:45:07 ft1905-febf volumio[4041]: info: Loading i18n strings for locale en May 28 16:45:07 ft1905-febf volumio[4041]: Updating browse sources language May 28 16:45:07 ft1905-febf volumio[4041]: Cannot find translation for sourceMedia Servers May 28 16:45:07 ft1905-febf volumio[4041]: Cannot find translation for sourcePodcast May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 16:45:07 ft1905-febf volumio[4041]: info: CoreCommandRouter::initPlayerControls May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 16:45:08 ft1905-febf volumio[4041]: info: BOOT COMPLETED May 28 16:45:08 ft1905-febf volumio[4041]: [Metrics] CommandRouter: 12s 603.89ms May 28 16:45:08 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 16:45:08 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 May 28 16:45:08 ft1905-febf socketio[2415]: [2025-05-28 16:45:08] [connect] Successful connection May 28 16:45:10 ft1905-febf systemd[1]: Started Music Player Daemon. May 28 16:45:10 ft1905-febf sudo[4107]: pam_unix(sudo:session): session closed for user root May 28 16:45:13 ft1905-febf volumio[4041]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreCommandRouter::Close All Modals sent May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreCommandRouter::Close All Modals sent May 28 16:45:13 ft1905-febf volumio[4041]: Express server listening on port 3000 May 28 16:45:13 ft1905-febf volumio[4041]: [Metrics] WebUI: 18s 754.20ms May 28 16:45:13 ft1905-febf volumio[4041]: (node:4041) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 28 16:45:13 ft1905-febf volumio[4041]: info: [1748421913815] ControllerPandora::validateAndSetAccountOptions May 28 16:45:13 ft1905-febf volumio[4041]: error: [1748421913818] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreStateMachine::resetVolumioState May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreStateMachine::getcurrentVolume May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioRetrievevolume May 28 16:45:13 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 16:45:14 ft1905-febf sudo[4153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 16:45:14 ft1905-febf sudo[4153]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:45:14 ft1905-febf sudo[4153]: pam_unix(sudo:session): session closed for user root May 28 16:45:14 ft1905-febf sudo[4157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 16:45:14 ft1905-febf sudo[4157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 16:45:14 ft1905-febf volumio[4041]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 28 16:45:14 ft1905-febf sudo[4157]: pam_unix(sudo:session): session closed for user root May 28 16:45:14 ft1905-febf volumio[4041]: info: this.stateMachine.currentVolume: null, vol: 0 May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreStateMachine::pushState May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreStateMachine::updateTrackBlock May 28 16:45:14 ft1905-febf volumio[4041]: info: CorePlayQueue::getTrackBlock May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::volumioRetrievevolume May 28 16:45:14 ft1905-febf volumio[4041]: info: MPD Permissions set May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 16:45:14 ft1905-febf volumio[4041]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 16:45:15 ft1905-febf volumio[4041]: Device response: { SERVER: May 28 16:45:15 ft1905-febf volumio[4041]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', May 28 16:45:15 ft1905-febf volumio[4041]: 'CONFIGID.UPNP.ORG': '0', May 28 16:45:15 ft1905-febf volumio[4041]: LOCATION: May 28 16:45:15 ft1905-febf volumio[4041]: 'http://192.168.100.112:9365/MediaServer/DeviceDescription.xml', May 28 16:45:15 ft1905-febf volumio[4041]: USN: May 28 16:45:15 ft1905-febf volumio[4041]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', May 28 16:45:15 ft1905-febf volumio[4041]: 'BOOTID.UPNP.ORG': '1748229974', May 28 16:45:15 ft1905-febf volumio[4041]: EXT: '', May 28 16:45:15 ft1905-febf volumio[4041]: DATE: 'Wed, 28 May 2025 08:45:14 GMT', May 28 16:45:15 ft1905-febf volumio[4041]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', May 28 16:45:15 ft1905-febf volumio[4041]: 'CACHE-CONTROL': 'max-age=1800' } May 28 16:45:15 ft1905-febf volumio[4041]: Device IP: 192.168.100.112 May 28 16:45:15 ft1905-febf volumio[4041]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 16:45:15 ft1905-febf volumio[4041]: ReferenceError: data is not defined May 28 16:45:15 ft1905-febf volumio[4041]: at Namespace.eval (webpack://Volumio2/./app/plugins/user_interface/websocket/index.js?:828:7) May 28 16:45:15 ft1905-febf volumio[4041]: at Namespace.emit (events.js:198:13) May 28 16:45:15 ft1905-febf volumio[4041]: at Namespace.emit (webpack://Volumio2/./node_modules/socket.io/lib/namespace.js?:209:10) May 28 16:45:15 ft1905-febf volumio[4041]: at eval (webpack://Volumio2/./node_modules/socket.io/lib/namespace.js?:177:14) May 28 16:45:15 ft1905-febf volumio[4041]: at process._tickCallback (internal/process/next_tick.js:61:11) May 28 16:45:15 ft1905-febf volumio[4041]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 16:45:16 ft1905-febf volumio[4178]: Forking 1 albumart workers May 28 16:45:17 ft1905-febf sudo[4207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 16:44 May 28 16:45:17 ft1905-febf sudo[4207]: 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.3.0.152 VOLUMIO_BUILD_DATE=2025-05-26 19:03:27 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system